{"level":"warn","ts":"2026-02-18T22:55:33.539037Z","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-18T22:55:33.53915Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.204.45.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-18T22:55:33.539274Z","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-18T22:55:33.539288Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-02-18T22:55:33.539332Z","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-18T22:55:33.540085Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-02-18T22:55:33.540269Z","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.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-18T22:55:33.582233Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"41.701099ms"} {"level":"info","ts":"2026-02-18T22:55:33.757664Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-02-18T22:55:33.757912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:55:33.757993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-02-18T22:55:33.758052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T22:55:33.758102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-02-18T22:55:33.758212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-02-18T22:55:33.826668Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:55:33.854894Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:55:33.856075Z","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-18T22:55:33.860966Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T22:55:33.861867Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T22:55:33.862481Z","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-18T22:55:33.862644Z","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-18T22:55:33.8627Z","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-18T22:55:33.863605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-02-18T22:55:33.863774Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-02-18T22:55:33.863856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:55:33.863923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:55:33.863967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-02-18T22:55:33.86404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:55:33.864074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-02-18T22:55:33.864134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-02-18T22:55:33.864169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-02-18T22:55:33.892826Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T22:55:33.893522Z","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-18T22:55:33.894581Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T22:55:33.894841Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-02-18T22:55:33.894899Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-02-18T22:55:33.895348Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:55:33.898713Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:55:33.898735Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T22:55:33.895757Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:55:33.903643Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:55:33.904456Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-02-18T22:55:33.904582Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:55:33.912582Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:55:33.912624Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:55:33.895768Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:55:33.906051Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T22:55:43.72355Z","caller":"traceutil/trace.go:171","msg":"trace[1529406074] transaction","detail":"{read_only:false; number_of_response:1; response_revision:350; }","duration":"100.591402ms","start":"2026-02-18T22:55:43.622916Z","end":"2026-02-18T22:55:43.723507Z","steps":["trace[1529406074] 'process raft request' (duration: 97.738852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:43.724521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.131133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3840"} {"level":"info","ts":"2026-02-18T22:55:43.724585Z","caller":"traceutil/trace.go:171","msg":"trace[1477831230] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:350; }","duration":"100.236085ms","start":"2026-02-18T22:55:43.624334Z","end":"2026-02-18T22:55:43.72457Z","steps":["trace[1477831230] 'agreement among raft nodes before linearized reading' (duration: 100.113052ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:46.29011Z","caller":"traceutil/trace.go:171","msg":"trace[1558960603] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"256.409839ms","start":"2026-02-18T22:55:46.033682Z","end":"2026-02-18T22:55:46.290092Z","steps":["trace[1558960603] 'read index received' (duration: 256.254105ms)","trace[1558960603] 'applied index is now lower than readState.Index' (duration: 154.914µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:46.29037Z","caller":"traceutil/trace.go:171","msg":"trace[958039387] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"266.82995ms","start":"2026-02-18T22:55:46.023528Z","end":"2026-02-18T22:55:46.290358Z","steps":["trace[958039387] 'process raft request' (duration: 266.464352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:46.290501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.796418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:46.290523Z","caller":"traceutil/trace.go:171","msg":"trace[905062003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"256.853439ms","start":"2026-02-18T22:55:46.033664Z","end":"2026-02-18T22:55:46.290518Z","steps":["trace[905062003] 'agreement among raft nodes before linearized reading' (duration: 256.798508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:46.290594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.426463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T22:55:46.290632Z","caller":"traceutil/trace.go:171","msg":"trace[1331926133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:399; }","duration":"149.497685ms","start":"2026-02-18T22:55:46.14113Z","end":"2026-02-18T22:55:46.290628Z","steps":["trace[1331926133] 'agreement among raft nodes before linearized reading' (duration: 149.450254ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:52.309296Z","caller":"traceutil/trace.go:171","msg":"trace[1908185709] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"497.422823ms","start":"2026-02-18T22:55:51.811848Z","end":"2026-02-18T22:55:52.309271Z","steps":["trace[1908185709] 'process raft request' (duration: 497.264609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:52.309874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:51.81183Z","time spent":"497.535716ms","remote":"127.0.0.1:40828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:52.502361Z","caller":"traceutil/trace.go:171","msg":"trace[307528620] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"181.869127ms","start":"2026-02-18T22:55:52.320468Z","end":"2026-02-18T22:55:52.502337Z","steps":["trace[307528620] 'process raft request' (duration: 178.646859ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:52.503467Z","caller":"traceutil/trace.go:171","msg":"trace[330314809] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:434; }","duration":"149.308869ms","start":"2026-02-18T22:55:52.35415Z","end":"2026-02-18T22:55:52.503459Z","steps":["trace[330314809] 'read index received' (duration: 144.974558ms)","trace[330314809] 'applied index is now lower than readState.Index' (duration: 4.332971ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:52.503593Z","caller":"traceutil/trace.go:171","msg":"trace[646832890] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"181.734153ms","start":"2026-02-18T22:55:52.321852Z","end":"2026-02-18T22:55:52.503586Z","steps":["trace[646832890] 'process raft request' (duration: 181.547659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:52.503695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.546446ms","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-18T22:55:52.503716Z","caller":"traceutil/trace.go:171","msg":"trace[184336980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"149.616247ms","start":"2026-02-18T22:55:52.354093Z","end":"2026-02-18T22:55:52.503709Z","steps":["trace[184336980] 'agreement among raft nodes before linearized reading' (duration: 149.568195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:52.503796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.022913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:52.503812Z","caller":"traceutil/trace.go:171","msg":"trace[2030031477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"148.249267ms","start":"2026-02-18T22:55:52.355558Z","end":"2026-02-18T22:55:52.503807Z","steps":["trace[2030031477] 'agreement among raft nodes before linearized reading' (duration: 148.223147ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:52.982497Z","caller":"traceutil/trace.go:171","msg":"trace[140761285] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"163.186492ms","start":"2026-02-18T22:55:52.819295Z","end":"2026-02-18T22:55:52.982482Z","steps":["trace[140761285] 'process raft request' (duration: 163.096721ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:53.065172Z","caller":"traceutil/trace.go:171","msg":"trace[2019930649] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"119.166785ms","start":"2026-02-18T22:55:52.945986Z","end":"2026-02-18T22:55:53.065152Z","steps":["trace[2019930649] 'read index received' (duration: 36.845019ms)","trace[2019930649] 'applied index is now lower than readState.Index' (duration: 82.320656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:53.065282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.275197ms","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-18T22:55:53.065305Z","caller":"traceutil/trace.go:171","msg":"trace[1094774523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:454; }","duration":"119.339348ms","start":"2026-02-18T22:55:52.945958Z","end":"2026-02-18T22:55:53.065297Z","steps":["trace[1094774523] 'agreement among raft nodes before linearized reading' (duration: 119.260856ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:04.145078Z","caller":"traceutil/trace.go:171","msg":"trace[479948840] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"210.722815ms","start":"2026-02-18T22:56:03.934329Z","end":"2026-02-18T22:56:04.145051Z","steps":["trace[479948840] 'process raft request' (duration: 210.344867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.330515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.601073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:04.330852Z","caller":"traceutil/trace.go:171","msg":"trace[1175703958] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"158.605986ms","start":"2026-02-18T22:56:04.17223Z","end":"2026-02-18T22:56:04.330836Z","steps":["trace[1175703958] 'process raft request' (duration: 158.540104ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:04.330926Z","caller":"traceutil/trace.go:171","msg":"trace[901002340] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"159.409172ms","start":"2026-02-18T22:56:04.171467Z","end":"2026-02-18T22:56:04.330876Z","steps":["trace[901002340] 'process raft request' (duration: 30.241948ms)","trace[901002340] 'compare' (duration: 128.443989ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:04.924239Z","caller":"traceutil/trace.go:171","msg":"trace[1479626466] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"136.355835ms","start":"2026-02-18T22:56:04.787856Z","end":"2026-02-18T22:56:04.924211Z","steps":["trace[1479626466] 'process raft request' (duration: 120.370739ms)","trace[1479626466] 'compare' (duration: 15.796323ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:04.924373Z","caller":"traceutil/trace.go:171","msg":"trace[939855147] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:521; }","duration":"132.29516ms","start":"2026-02-18T22:56:04.792058Z","end":"2026-02-18T22:56:04.924353Z","steps":["trace[939855147] 'read index received' (duration: 43.123799ms)","trace[939855147] 'applied index is now lower than readState.Index' (duration: 89.170521ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:04.924477Z","caller":"traceutil/trace.go:171","msg":"trace[1849346910] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"136.446968ms","start":"2026-02-18T22:56:04.788021Z","end":"2026-02-18T22:56:04.924468Z","steps":["trace[1849346910] 'process raft request' (duration: 136.125951ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:04.924683Z","caller":"traceutil/trace.go:171","msg":"trace[1657095150] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"132.847252ms","start":"2026-02-18T22:56:04.791827Z","end":"2026-02-18T22:56:04.924675Z","steps":["trace[1657095150] 'process raft request' (duration: 132.356442ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:04.924702Z","caller":"traceutil/trace.go:171","msg":"trace[1696679549] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"132.28016ms","start":"2026-02-18T22:56:04.792412Z","end":"2026-02-18T22:56:04.924692Z","steps":["trace[1696679549] 'process raft request' (duration: 131.891372ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:04.924829Z","caller":"traceutil/trace.go:171","msg":"trace[1415995474] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"132.848622ms","start":"2026-02-18T22:56:04.791973Z","end":"2026-02-18T22:56:04.924821Z","steps":["trace[1415995474] 'process raft request' (duration: 132.290221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.924866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.791711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mh88q\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-18T22:56:04.924898Z","caller":"traceutil/trace.go:171","msg":"trace[241096817] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mh88q; range_end:; response_count:1; response_revision:509; }","duration":"132.850423ms","start":"2026-02-18T22:56:04.792038Z","end":"2026-02-18T22:56:04.924888Z","steps":["trace[241096817] 'agreement among raft nodes before linearized reading' (duration: 132.773951ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:05.066581Z","caller":"traceutil/trace.go:171","msg":"trace[108771053] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"115.785453ms","start":"2026-02-18T22:56:04.950776Z","end":"2026-02-18T22:56:05.066561Z","steps":["trace[108771053] 'process raft request' (duration: 115.696601ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:05.06694Z","caller":"traceutil/trace.go:171","msg":"trace[2108851578] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"128.9268ms","start":"2026-02-18T22:56:04.937993Z","end":"2026-02-18T22:56:05.06692Z","steps":["trace[2108851578] 'process raft request' (duration: 98.537189ms)","trace[2108851578] 'compare' (duration: 29.805479ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:05.104164Z","caller":"traceutil/trace.go:171","msg":"trace[67368052] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"100.224934ms","start":"2026-02-18T22:56:05.003922Z","end":"2026-02-18T22:56:05.104147Z","steps":["trace[67368052] 'process raft request' (duration: 99.827146ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:05.439287Z","caller":"traceutil/trace.go:171","msg":"trace[1278185097] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"160.183549ms","start":"2026-02-18T22:56:05.279085Z","end":"2026-02-18T22:56:05.439268Z","steps":["trace[1278185097] 'read index received' (duration: 160.050737ms)","trace[1278185097] 'applied index is now lower than readState.Index' (duration: 131.802µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:05.439393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.289261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-kvm8m\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-02-18T22:56:05.439414Z","caller":"traceutil/trace.go:171","msg":"trace[1214631095] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-kvm8m; range_end:; response_count:1; response_revision:515; }","duration":"160.363643ms","start":"2026-02-18T22:56:05.279044Z","end":"2026-02-18T22:56:05.439408Z","steps":["trace[1214631095] 'agreement among raft nodes before linearized reading' (duration: 160.292392ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:05.439584Z","caller":"traceutil/trace.go:171","msg":"trace[76863025] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"160.504235ms","start":"2026-02-18T22:56:05.279049Z","end":"2026-02-18T22:56:05.439553Z","steps":["trace[76863025] 'process raft request' (duration: 160.093677ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:09.021386Z","caller":"traceutil/trace.go:171","msg":"trace[586981185] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"231.713089ms","start":"2026-02-18T22:56:08.789651Z","end":"2026-02-18T22:56:09.021365Z","steps":["trace[586981185] 'process raft request' (duration: 231.581296ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:09.02189Z","caller":"traceutil/trace.go:171","msg":"trace[977799296] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:544; }","duration":"231.358811ms","start":"2026-02-18T22:56:08.790519Z","end":"2026-02-18T22:56:09.021878Z","steps":["trace[977799296] 'read index received' (duration: 230.648877ms)","trace[977799296] 'applied index is now lower than readState.Index' (duration: 708.644µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:09.021982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.448943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:56:09.022012Z","caller":"traceutil/trace.go:171","msg":"trace[989059606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"231.522085ms","start":"2026-02-18T22:56:08.79048Z","end":"2026-02-18T22:56:09.022002Z","steps":["trace[989059606] 'agreement among raft nodes before linearized reading' (duration: 231.457073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.022311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.834729ms","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-18T22:56:09.022343Z","caller":"traceutil/trace.go:171","msg":"trace[458287581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"230.880961ms","start":"2026-02-18T22:56:08.791451Z","end":"2026-02-18T22:56:09.022332Z","steps":["trace[458287581] 'agreement among raft nodes before linearized reading' (duration: 230.800009ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:09.771173Z","caller":"traceutil/trace.go:171","msg":"trace[265305322] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"123.76529ms","start":"2026-02-18T22:56:09.647387Z","end":"2026-02-18T22:56:09.771153Z","steps":["trace[265305322] 'process raft request' (duration: 123.410893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.771563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.108688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:09.771597Z","caller":"traceutil/trace.go:171","msg":"trace[656309223] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"124.16291ms","start":"2026-02-18T22:56:09.647422Z","end":"2026-02-18T22:56:09.771585Z","steps":["trace[656309223] 'agreement among raft nodes before linearized reading' (duration: 124.077479ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:09.77146Z","caller":"traceutil/trace.go:171","msg":"trace[887558058] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:551; }","duration":"123.518275ms","start":"2026-02-18T22:56:09.647436Z","end":"2026-02-18T22:56:09.770955Z","steps":["trace[887558058] 'read index received' (duration: 18.47805ms)","trace[887558058] 'applied index is now lower than readState.Index' (duration: 105.039355ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:09.772016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.754832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:09.772049Z","caller":"traceutil/trace.go:171","msg":"trace[717377445] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"106.801463ms","start":"2026-02-18T22:56:09.665236Z","end":"2026-02-18T22:56:09.772038Z","steps":["trace[717377445] 'agreement among raft nodes before linearized reading' (duration: 106.755762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.772193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.119236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:09.772218Z","caller":"traceutil/trace.go:171","msg":"trace[1495316995] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"104.144216ms","start":"2026-02-18T22:56:09.668066Z","end":"2026-02-18T22:56:09.77221Z","steps":["trace[1495316995] 'agreement among raft nodes before linearized reading' (duration: 104.108766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.7724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.345821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:09.772428Z","caller":"traceutil/trace.go:171","msg":"trace[2071561296] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"104.381552ms","start":"2026-02-18T22:56:09.668037Z","end":"2026-02-18T22:56:09.772418Z","steps":["trace[2071561296] 'agreement among raft nodes before linearized reading' (duration: 104.344691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.77253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.746042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:09.772554Z","caller":"traceutil/trace.go:171","msg":"trace[748876913] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"106.777822ms","start":"2026-02-18T22:56:09.665769Z","end":"2026-02-18T22:56:09.772546Z","steps":["trace[748876913] 'agreement among raft nodes before linearized reading' (duration: 106.745782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.772909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.668574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:09.772928Z","caller":"traceutil/trace.go:171","msg":"trace[2111234818] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"110.696945ms","start":"2026-02-18T22:56:09.662226Z","end":"2026-02-18T22:56:09.772923Z","steps":["trace[2111234818] 'agreement among raft nodes before linearized reading' (duration: 110.667774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.239485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:09.773016Z","caller":"traceutil/trace.go:171","msg":"trace[155153375] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"109.342827ms","start":"2026-02-18T22:56:09.663668Z","end":"2026-02-18T22:56:09.773011Z","steps":["trace[155153375] 'agreement among raft nodes before linearized reading' (duration: 109.320386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.773157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.613398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:09.773175Z","caller":"traceutil/trace.go:171","msg":"trace[1541815792] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"114.641168ms","start":"2026-02-18T22:56:09.658528Z","end":"2026-02-18T22:56:09.773169Z","steps":["trace[1541815792] 'agreement among raft nodes before linearized reading' (duration: 114.615248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.773585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.052018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-kvm8m\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-02-18T22:56:09.77361Z","caller":"traceutil/trace.go:171","msg":"trace[2125500669] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-kvm8m; range_end:; response_count:1; response_revision:535; }","duration":"116.095928ms","start":"2026-02-18T22:56:09.657507Z","end":"2026-02-18T22:56:09.773603Z","steps":["trace[2125500669] 'agreement among raft nodes before linearized reading' (duration: 114.426614ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:11.119091Z","caller":"traceutil/trace.go:171","msg":"trace[740488429] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"277.069495ms","start":"2026-02-18T22:56:10.842006Z","end":"2026-02-18T22:56:11.119075Z","steps":["trace[740488429] 'process raft request' (duration: 272.736343ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:11.805396Z","caller":"traceutil/trace.go:171","msg":"trace[137760468] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"681.290983ms","start":"2026-02-18T22:56:11.124091Z","end":"2026-02-18T22:56:11.805382Z","steps":["trace[137760468] 'process raft request' (duration: 681.255342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:11.805474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.124086Z","time spent":"681.347524ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:11.805631Z","caller":"traceutil/trace.go:171","msg":"trace[866302559] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"681.65602ms","start":"2026-02-18T22:56:11.123969Z","end":"2026-02-18T22:56:11.805625Z","steps":["trace[866302559] 'process raft request' (duration: 670.487904ms)","trace[866302559] 'compare' (duration: 10.398929ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:11.805659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.123956Z","time spent":"681.688321ms","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:14.014719Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.219854258s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:56:14.388657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000229016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T22:56:14.388736Z","caller":"traceutil/trace.go:171","msg":"trace[635405105] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000372758s","start":"2026-02-18T22:56:12.388344Z","end":"2026-02-18T22:56:14.388717Z","steps":["trace[635405105] 'agreement among raft nodes before linearized reading' (duration: 2.000251216s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.388777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.38832Z","time spent":"2.00044841s","remote":"127.0.0.1:40372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:56:14.580079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.100148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:14.580291Z","caller":"traceutil/trace.go:171","msg":"trace[2020430557] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"3.45215225s","start":"2026-02-18T22:56:11.128124Z","end":"2026-02-18T22:56:14.580276Z","steps":["trace[2020430557] 'process raft request' (duration: 2.886794717s)","trace[2020430557] 'compare' (duration: 564.73442ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:14.580302Z","caller":"traceutil/trace.go:171","msg":"trace[1619789814] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:574; }","duration":"3.092072895s","start":"2026-02-18T22:56:11.488195Z","end":"2026-02-18T22:56:14.580268Z","steps":["trace[1619789814] 'read index received' (duration: 306.511396ms)","trace[1619789814] 'applied index is now lower than readState.Index' (duration: 2.785560769s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:14.580345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.128117Z","time spent":"3.452201431s","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:14.580355Z","caller":"traceutil/trace.go:171","msg":"trace[1771372194] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"3.449469894s","start":"2026-02-18T22:56:11.130878Z","end":"2026-02-18T22:56:14.580348Z","steps":["trace[1771372194] 'process raft request' (duration: 3.449268919s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.580395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.13086Z","time spent":"3.449509954s","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:14.580546Z","caller":"traceutil/trace.go:171","msg":"trace[431859578] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"3.336385148s","start":"2026-02-18T22:56:11.244153Z","end":"2026-02-18T22:56:14.580539Z","steps":["trace[431859578] 'process raft request' (duration: 3.336034221s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.580583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.244135Z","time spent":"3.33642969s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:56:14.580591Z","caller":"traceutil/trace.go:171","msg":"trace[1246555450] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"2.935679885s","start":"2026-02-18T22:56:11.644904Z","end":"2026-02-18T22:56:14.580584Z","steps":["trace[1246555450] 'process raft request' (duration: 2.935335028s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.580625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.644889Z","time spent":"2.935718587s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:56:14.580682Z","caller":"traceutil/trace.go:171","msg":"trace[356146665] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"3.136883429s","start":"2026-02-18T22:56:11.443789Z","end":"2026-02-18T22:56:14.580672Z","steps":["trace[356146665] 'process raft request' (duration: 3.13642768s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.580725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.443765Z","time spent":"3.13694105s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:56:14.580737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.092536834s","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-18T22:56:14.580761Z","caller":"traceutil/trace.go:171","msg":"trace[618193792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"3.092585535s","start":"2026-02-18T22:56:11.488168Z","end":"2026-02-18T22:56:14.580753Z","steps":["trace[618193792] 'agreement among raft nodes before linearized reading' (duration: 3.092523244s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.580785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.488155Z","time spent":"3.092624127s","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:56:14.742696Z","caller":"traceutil/trace.go:171","msg":"trace[929119006] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"2.929064342s","start":"2026-02-18T22:56:11.813606Z","end":"2026-02-18T22:56:14.742671Z","steps":["trace[929119006] 'process raft request' (duration: 2.927986249s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.742809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.813593Z","time spent":"2.929156323s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:14.743965Z","caller":"traceutil/trace.go:171","msg":"trace[119704650] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"2.870988037s","start":"2026-02-18T22:56:11.872959Z","end":"2026-02-18T22:56:14.743947Z","steps":["trace[119704650] 'process raft request' (duration: 2.86877446s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.74406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.872944Z","time spent":"2.871066379s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:56:14.744295Z","caller":"traceutil/trace.go:171","msg":"trace[891409312] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"2.930625105s","start":"2026-02-18T22:56:11.813661Z","end":"2026-02-18T22:56:14.744286Z","steps":["trace[891409312] 'process raft request' (duration: 2.928010339s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.744344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.813658Z","time spent":"2.930653846s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:14.744573Z","caller":"traceutil/trace.go:171","msg":"trace[2040053415] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"2.657239688s","start":"2026-02-18T22:56:12.087325Z","end":"2026-02-18T22:56:14.744564Z","steps":["trace[2040053415] 'process raft request' (duration: 2.654680834s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.744604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.08731Z","time spent":"2.65727748s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:56:14.754953Z","caller":"traceutil/trace.go:171","msg":"trace[295004182] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"163.943595ms","start":"2026-02-18T22:56:14.590994Z","end":"2026-02-18T22:56:14.754937Z","steps":["trace[295004182] 'process raft request' (duration: 163.844492ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:14.75524Z","caller":"traceutil/trace.go:171","msg":"trace[1630107058] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"174.903698ms","start":"2026-02-18T22:56:14.580324Z","end":"2026-02-18T22:56:14.755228Z","steps":["trace[1630107058] 'read index received' (duration: 162.631447ms)","trace[1630107058] 'applied index is now lower than readState.Index' (duration: 12.268081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:14.755353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.053496359s","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-18T22:56:14.755385Z","caller":"traceutil/trace.go:171","msg":"trace[623711412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:568; }","duration":"3.05355912s","start":"2026-02-18T22:56:11.701816Z","end":"2026-02-18T22:56:14.755375Z","steps":["trace[623711412] 'agreement among raft nodes before linearized reading' (duration: 3.053455018s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.755438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.701803Z","time spent":"3.053628482s","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:14.755602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145600689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.755628Z","caller":"traceutil/trace.go:171","msg":"trace[2023844537] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:568; }","duration":"1.145644041s","start":"2026-02-18T22:56:13.609977Z","end":"2026-02-18T22:56:14.755621Z","steps":["trace[2023844537] 'agreement among raft nodes before linearized reading' (duration: 1.14560331s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.755646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.609877Z","time spent":"1.145764323s","remote":"127.0.0.1:51482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-02-18T22:56:14.755772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514414751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4171"} {"level":"info","ts":"2026-02-18T22:56:14.755796Z","caller":"traceutil/trace.go:171","msg":"trace[942217391] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"1.514437682s","start":"2026-02-18T22:56:13.241352Z","end":"2026-02-18T22:56:14.755789Z","steps":["trace[942217391] 'agreement among raft nodes before linearized reading' (duration: 1.51437909s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.755817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.241326Z","time spent":"1.514487533s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4195,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:14.755606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145567959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T22:56:14.755905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.568012692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.75593Z","caller":"traceutil/trace.go:171","msg":"trace[93336109] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:568; }","duration":"1.568043422s","start":"2026-02-18T22:56:13.187879Z","end":"2026-02-18T22:56:14.755923Z","steps":["trace[93336109] 'agreement among raft nodes before linearized reading' (duration: 1.568011412s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.755952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.187875Z","time spent":"1.568071773s","remote":"127.0.0.1:51468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:14.756044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.568155515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.756067Z","caller":"traceutil/trace.go:171","msg":"trace[935317182] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:568; }","duration":"1.568192645s","start":"2026-02-18T22:56:13.187869Z","end":"2026-02-18T22:56:14.756061Z","steps":["trace[935317182] 'agreement among raft nodes before linearized reading' (duration: 1.568159834s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.756089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.187863Z","time spent":"1.568220786s","remote":"127.0.0.1:51468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"info","ts":"2026-02-18T22:56:14.755994Z","caller":"traceutil/trace.go:171","msg":"trace[730348843] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:568; }","duration":"1.145897906s","start":"2026-02-18T22:56:13.610011Z","end":"2026-02-18T22:56:14.755909Z","steps":["trace[730348843] 'agreement among raft nodes before linearized reading' (duration: 1.145531248s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.756163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.568288707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.756186Z","caller":"traceutil/trace.go:171","msg":"trace[1504467256] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:568; }","duration":"1.568329799s","start":"2026-02-18T22:56:13.18785Z","end":"2026-02-18T22:56:14.75618Z","steps":["trace[1504467256] 'agreement among raft nodes before linearized reading' (duration: 1.568297578s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.756208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.187837Z","time spent":"1.568366129s","remote":"127.0.0.1:51468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T22:56:14.756171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.610008Z","time spent":"1.146153921s","remote":"127.0.0.1:51482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:14.756431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.096168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.756485Z","caller":"traceutil/trace.go:171","msg":"trace[661432580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"367.17918ms","start":"2026-02-18T22:56:14.389296Z","end":"2026-02-18T22:56:14.756475Z","steps":["trace[661432580] 'agreement among raft nodes before linearized reading' (duration: 367.104498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.756512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:14.389276Z","time spent":"367.23042ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:56:14.75662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.143694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:56:14.756687Z","caller":"traceutil/trace.go:171","msg":"trace[407418359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"939.242876ms","start":"2026-02-18T22:56:13.817439Z","end":"2026-02-18T22:56:14.756682Z","steps":["trace[407418359] 'agreement among raft nodes before linearized reading' (duration: 939.161264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.757445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.817425Z","time spent":"940.013832ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:14.757577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125960525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.757637Z","caller":"traceutil/trace.go:171","msg":"trace[1101561907] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:568; }","duration":"1.126033156s","start":"2026-02-18T22:56:13.631597Z","end":"2026-02-18T22:56:14.75763Z","steps":["trace[1101561907] 'agreement among raft nodes before linearized reading' (duration: 1.125962484s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.757692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.631592Z","time spent":"1.126092747s","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:14.757841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126250782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.757898Z","caller":"traceutil/trace.go:171","msg":"trace[509066281] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:568; }","duration":"1.126314964s","start":"2026-02-18T22:56:13.631575Z","end":"2026-02-18T22:56:14.75789Z","steps":["trace[509066281] 'agreement among raft nodes before linearized reading' (duration: 1.126250653s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.757952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.631569Z","time spent":"1.126363755s","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"warn","ts":"2026-02-18T22:56:14.758191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716678808s","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-18T22:56:14.758224Z","caller":"traceutil/trace.go:171","msg":"trace[1951526146] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"1.71676467s","start":"2026-02-18T22:56:13.041451Z","end":"2026-02-18T22:56:14.758216Z","steps":["trace[1951526146] 'agreement among raft nodes before linearized reading' (duration: 1.716681398s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.75825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.041433Z","time spent":"1.716811091s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:14.758327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126785013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T22:56:14.758368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.916652806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-18T22:56:14.758393Z","caller":"traceutil/trace.go:171","msg":"trace[381955855] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"1.916711478s","start":"2026-02-18T22:56:12.841675Z","end":"2026-02-18T22:56:14.758387Z","steps":["trace[381955855] 'agreement among raft nodes before linearized reading' (duration: 1.916643536s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.758415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.841657Z","time spent":"1.916753648s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24268,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:14.75847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.116884091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-18T22:56:14.758493Z","caller":"traceutil/trace.go:171","msg":"trace[23311426] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"2.116937632s","start":"2026-02-18T22:56:12.641549Z","end":"2026-02-18T22:56:14.758487Z","steps":["trace[23311426] 'agreement among raft nodes before linearized reading' (duration: 2.116904251s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.758514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.641531Z","time spent":"2.116979493s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5201,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-18T22:56:14.758379Z","caller":"traceutil/trace.go:171","msg":"trace[1280301371] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:568; }","duration":"1.126855295s","start":"2026-02-18T22:56:13.631517Z","end":"2026-02-18T22:56:14.758372Z","steps":["trace[1280301371] 'agreement among raft nodes before linearized reading' (duration: 1.126519428s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.758938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.631501Z","time spent":"1.127428797s","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T22:56:14.759043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139268266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.759079Z","caller":"traceutil/trace.go:171","msg":"trace[1552638710] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:568; }","duration":"1.139322858s","start":"2026-02-18T22:56:13.619749Z","end":"2026-02-18T22:56:14.759072Z","steps":["trace[1552638710] 'agreement among raft nodes before linearized reading' (duration: 1.139275847s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.759106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.619746Z","time spent":"1.139353969s","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T22:56:14.760297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.319191467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-18T22:56:14.76033Z","caller":"traceutil/trace.go:171","msg":"trace[346559773] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"2.319252769s","start":"2026-02-18T22:56:12.44107Z","end":"2026-02-18T22:56:14.760323Z","steps":["trace[346559773] 'agreement among raft nodes before linearized reading' (duration: 2.317511493s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.76035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.441057Z","time spent":"2.31928802s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:14.76048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.519070264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-18T22:56:14.760498Z","caller":"traceutil/trace.go:171","msg":"trace[668946789] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"2.519123136s","start":"2026-02-18T22:56:12.24137Z","end":"2026-02-18T22:56:14.760493Z","steps":["trace[668946789] 'agreement among raft nodes before linearized reading' (duration: 2.519064174s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.760515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.241352Z","time spent":"2.519158986s","remote":"127.0.0.1:40434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:56:14.759218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139474441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.761696Z","caller":"traceutil/trace.go:171","msg":"trace[202380508] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:568; }","duration":"1.141957194s","start":"2026-02-18T22:56:13.61973Z","end":"2026-02-18T22:56:14.761687Z","steps":["trace[202380508] 'agreement among raft nodes before linearized reading' (duration: 1.139471911s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.761736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.619727Z","time spent":"1.141997814s","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:14.761935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14221689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.761999Z","caller":"traceutil/trace.go:171","msg":"trace[1249061149] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:568; }","duration":"1.142282611s","start":"2026-02-18T22:56:13.61971Z","end":"2026-02-18T22:56:14.761993Z","steps":["trace[1249061149] 'agreement among raft nodes before linearized reading' (duration: 1.142205079s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.762061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.619692Z","time spent":"1.142361192s","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-18T22:56:14.762234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1521987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.762296Z","caller":"traceutil/trace.go:171","msg":"trace[2010437940] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:568; }","duration":"1.152268021s","start":"2026-02-18T22:56:13.610022Z","end":"2026-02-18T22:56:14.76229Z","steps":["trace[2010437940] 'agreement among raft nodes before linearized reading' (duration: 1.15219333s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.762363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.610019Z","time spent":"1.152335372s","remote":"127.0.0.1:51482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T22:56:17.08571Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301868417614532,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:56:17.17479Z","caller":"traceutil/trace.go:171","msg":"trace[637353673] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"630.306597ms","start":"2026-02-18T22:56:16.544172Z","end":"2026-02-18T22:56:17.174479Z","steps":["trace[637353673] 'process raft request' (duration: 630.090483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:17.174907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.544151Z","time spent":"630.694146ms","remote":"127.0.0.1:40544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:18.85148Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.677214943s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:56:18.851716Z","caller":"traceutil/trace.go:171","msg":"trace[363915263] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"2.266140384s","start":"2026-02-18T22:56:16.58556Z","end":"2026-02-18T22:56:18.851701Z","steps":["trace[363915263] 'read index received' (duration: 588.748247ms)","trace[363915263] 'applied index is now lower than readState.Index' (duration: 1.677391577s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:18.851902Z","caller":"traceutil/trace.go:171","msg":"trace[725643801] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.990585395s","start":"2026-02-18T22:56:16.861311Z","end":"2026-02-18T22:56:18.851897Z","steps":["trace[725643801] 'process raft request' (duration: 1.990284858s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.851973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.861298Z","time spent":"1.990628935s","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:18.852122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.266561222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T22:56:18.852138Z","caller":"traceutil/trace.go:171","msg":"trace[1836069257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"2.266602354s","start":"2026-02-18T22:56:16.58553Z","end":"2026-02-18T22:56:18.852133Z","steps":["trace[1836069257] 'agreement among raft nodes before linearized reading' (duration: 2.266541612s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.852151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.585517Z","time spent":"2.266631234s","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:56:18.852238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.265873919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-18T22:56:18.869264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480239236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:18.869289Z","caller":"traceutil/trace.go:171","msg":"trace[599890634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"1.480291658s","start":"2026-02-18T22:56:17.388991Z","end":"2026-02-18T22:56:18.869283Z","steps":["trace[599890634] 'agreement among raft nodes before linearized reading' (duration: 1.480249617s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.869304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:17.388977Z","time spent":"1.480323808s","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:56:18.869417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054377272s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:18.869433Z","caller":"traceutil/trace.go:171","msg":"trace[37915113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"1.054418783s","start":"2026-02-18T22:56:17.81501Z","end":"2026-02-18T22:56:18.869429Z","steps":["trace[37915113] 'agreement among raft nodes before linearized reading' (duration: 1.054394132s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.869445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:17.814995Z","time spent":"1.054447624s","remote":"127.0.0.1:40372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:56:18.869517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.444240044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T22:56:18.869531Z","caller":"traceutil/trace.go:171","msg":"trace[199299800] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:584; }","duration":"1.444253094s","start":"2026-02-18T22:56:17.425273Z","end":"2026-02-18T22:56:18.869526Z","steps":["trace[199299800] 'agreement among raft nodes before linearized reading' (duration: 1.444228263s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.869543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:17.425246Z","time spent":"1.444294834s","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"warn","ts":"2026-02-18T22:56:18.869863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.091742576s","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-18T22:56:18.869879Z","caller":"traceutil/trace.go:171","msg":"trace[234593824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"2.091758136s","start":"2026-02-18T22:56:16.778116Z","end":"2026-02-18T22:56:18.869874Z","steps":["trace[234593824] 'agreement among raft nodes before linearized reading' (duration: 2.091713545s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.869893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.77808Z","time spent":"2.091808377s","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T22:56:18.869969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.046420026s","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-18T22:56:18.869981Z","caller":"traceutil/trace.go:171","msg":"trace[475423979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"2.046448246s","start":"2026-02-18T22:56:16.823529Z","end":"2026-02-18T22:56:18.869977Z","steps":["trace[475423979] 'agreement among raft nodes before linearized reading' (duration: 2.046410155s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.869995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.823522Z","time spent":"2.046470027s","remote":"127.0.0.1:40636","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-18T22:56:18.870068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.059120785s","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-18T22:56:18.87008Z","caller":"traceutil/trace.go:171","msg":"trace[417844420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:584; }","duration":"2.059145555s","start":"2026-02-18T22:56:16.81093Z","end":"2026-02-18T22:56:18.870076Z","steps":["trace[417844420] 'agreement among raft nodes before linearized reading' (duration: 2.059108895s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.870092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.810923Z","time spent":"2.059165096s","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:18.870245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.167046173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:18.87026Z","caller":"traceutil/trace.go:171","msg":"trace[445102905] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:584; }","duration":"2.167068783s","start":"2026-02-18T22:56:16.703186Z","end":"2026-02-18T22:56:18.870255Z","steps":["trace[445102905] 'agreement among raft nodes before linearized reading' (duration: 2.167042332s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.870273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.703184Z","time spent":"2.167085564s","remote":"127.0.0.1:51526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-02-18T22:56:18.870347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.167137385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:18.87036Z","caller":"traceutil/trace.go:171","msg":"trace[1392069972] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:584; }","duration":"2.167156195s","start":"2026-02-18T22:56:16.7032Z","end":"2026-02-18T22:56:18.870356Z","steps":["trace[1392069972] 'agreement among raft nodes before linearized reading' (duration: 2.167135905s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.870375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.703197Z","time spent":"2.167174275s","remote":"127.0.0.1:51526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:18.870503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.167330299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:18.870517Z","caller":"traceutil/trace.go:171","msg":"trace[1846556076] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:584; }","duration":"2.167364599s","start":"2026-02-18T22:56:16.703148Z","end":"2026-02-18T22:56:18.870513Z","steps":["trace[1846556076] 'agreement among raft nodes before linearized reading' (duration: 2.167342379s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.870532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.703138Z","time spent":"2.167388561s","remote":"127.0.0.1:51526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"info","ts":"2026-02-18T22:56:18.852249Z","caller":"traceutil/trace.go:171","msg":"trace[786759676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"2.265897719s","start":"2026-02-18T22:56:16.586348Z","end":"2026-02-18T22:56:18.852246Z","steps":["trace[786759676] 'agreement among raft nodes before linearized reading' (duration: 2.265860788s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.870683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.586341Z","time spent":"2.284337259s","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:56:24.679822Z","caller":"traceutil/trace.go:171","msg":"trace[1379161052] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"286.140044ms","start":"2026-02-18T22:56:24.393658Z","end":"2026-02-18T22:56:24.679798Z","steps":["trace[1379161052] 'read index received' (duration: 285.94017ms)","trace[1379161052] 'applied index is now lower than readState.Index' (duration: 198.704µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:24.679949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.269947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:24.679987Z","caller":"traceutil/trace.go:171","msg":"trace[1095371263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"286.368329ms","start":"2026-02-18T22:56:24.393606Z","end":"2026-02-18T22:56:24.679975Z","steps":["trace[1095371263] 'agreement among raft nodes before linearized reading' (duration: 286.296567ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:24.6802Z","caller":"traceutil/trace.go:171","msg":"trace[1153145182] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"972.170382ms","start":"2026-02-18T22:56:23.708005Z","end":"2026-02-18T22:56:24.680175Z","steps":["trace[1153145182] 'process raft request' (duration: 971.668131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:24.680321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:23.707977Z","time spent":"972.279126ms","remote":"127.0.0.1:40550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:29.854648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.844746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:56:29.854713Z","caller":"traceutil/trace.go:171","msg":"trace[1715876864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"610.951057ms","start":"2026-02-18T22:56:29.243749Z","end":"2026-02-18T22:56:29.8547Z","steps":["trace[1715876864] 'range keys from in-memory index tree' (duration: 610.700883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:29.854745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.243737Z","time spent":"611.001148ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:29.854792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.825605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:56:29.854859Z","caller":"traceutil/trace.go:171","msg":"trace[1418121793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"610.906836ms","start":"2026-02-18T22:56:29.243926Z","end":"2026-02-18T22:56:29.854833Z","steps":["trace[1418121793] 'range keys from in-memory index tree' (duration: 610.711362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:29.854892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.24392Z","time spent":"610.962117ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:29.854914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.663984ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:29.854961Z","caller":"traceutil/trace.go:171","msg":"trace[1521582129] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:633; }","duration":"754.709465ms","start":"2026-02-18T22:56:29.100243Z","end":"2026-02-18T22:56:29.854952Z","steps":["trace[1521582129] 'range keys from in-memory index tree' (duration: 754.654604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:29.855065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.593076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:29.855085Z","caller":"traceutil/trace.go:171","msg":"trace[1373766770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"464.638407ms","start":"2026-02-18T22:56:29.390441Z","end":"2026-02-18T22:56:29.855079Z","steps":["trace[1373766770] 'range keys from in-memory index tree' (duration: 464.550145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:29.855101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.390423Z","time spent":"464.674328ms","remote":"127.0.0.1:40372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:56:29.855102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.458079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T22:56:29.855127Z","caller":"traceutil/trace.go:171","msg":"trace[317022754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"712.499229ms","start":"2026-02-18T22:56:29.14262Z","end":"2026-02-18T22:56:29.855119Z","steps":["trace[317022754] 'range keys from in-memory index tree' (duration: 712.394827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:29.855145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.142592Z","time spent":"712.548371ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:56:29.855202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.233786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mh88q\" ","response":"range_response_count:1 size:17666"} {"level":"info","ts":"2026-02-18T22:56:29.855221Z","caller":"traceutil/trace.go:171","msg":"trace[1987524951] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mh88q; range_end:; response_count:1; response_revision:633; }","duration":"126.275006ms","start":"2026-02-18T22:56:29.72894Z","end":"2026-02-18T22:56:29.855215Z","steps":["trace[1987524951] 'range keys from in-memory index tree' (duration: 126.174064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:29.855279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.49049ms","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-18T22:56:29.855326Z","caller":"traceutil/trace.go:171","msg":"trace[1988807645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"680.534832ms","start":"2026-02-18T22:56:29.174759Z","end":"2026-02-18T22:56:29.855294Z","steps":["trace[1988807645] 'range keys from in-memory index tree' (duration: 680.383798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:29.855343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.174746Z","time spent":"680.592533ms","remote":"127.0.0.1:40636","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-18T22:56:29.855454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.369898ms","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-18T22:56:29.85547Z","caller":"traceutil/trace.go:171","msg":"trace[48293878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:633; }","duration":"671.423879ms","start":"2026-02-18T22:56:29.184041Z","end":"2026-02-18T22:56:29.855465Z","steps":["trace[48293878] 'range keys from in-memory index tree' (duration: 671.286276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:29.855485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.184022Z","time spent":"671.459461ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T22:56:30.234691Z","caller":"traceutil/trace.go:171","msg":"trace[1444196891] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"369.064951ms","start":"2026-02-18T22:56:29.86561Z","end":"2026-02-18T22:56:30.234675Z","steps":["trace[1444196891] 'process raft request' (duration: 368.942578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:30.234788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.865602Z","time spent":"369.138123ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:30.234838Z","caller":"traceutil/trace.go:171","msg":"trace[83429794] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"359.347976ms","start":"2026-02-18T22:56:29.875472Z","end":"2026-02-18T22:56:30.23482Z","steps":["trace[83429794] 'process raft request' (duration: 359.270854ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:30.234899Z","caller":"traceutil/trace.go:171","msg":"trace[64656923] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"370.759617ms","start":"2026-02-18T22:56:29.864134Z","end":"2026-02-18T22:56:30.234893Z","steps":["trace[64656923] 'process raft request' (duration: 370.276427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:30.234926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.864128Z","time spent":"370.782928ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:30.23495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.875464Z","time spent":"359.424637ms","remote":"127.0.0.1:40550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17813,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:30.234983Z","caller":"traceutil/trace.go:171","msg":"trace[1541853796] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"370.813009ms","start":"2026-02-18T22:56:29.864166Z","end":"2026-02-18T22:56:30.234979Z","steps":["trace[1541853796] 'process raft request' (duration: 370.334589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:30.235006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.864162Z","time spent":"370.831459ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:30.2351Z","caller":"traceutil/trace.go:171","msg":"trace[1894028058] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"368.501239ms","start":"2026-02-18T22:56:29.866591Z","end":"2026-02-18T22:56:30.235093Z","steps":["trace[1894028058] 'process raft request' (duration: 368.04941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:30.235139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:29.866583Z","time spent":"368.53506ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:32.051051Z","caller":"traceutil/trace.go:171","msg":"trace[1215795192] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"143.37202ms","start":"2026-02-18T22:56:31.907662Z","end":"2026-02-18T22:56:32.051034Z","steps":["trace[1215795192] 'read index received' (duration: 143.225416ms)","trace[1215795192] 'applied index is now lower than readState.Index' (duration: 145.953µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:32.051231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.561733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T22:56:32.051256Z","caller":"traceutil/trace.go:171","msg":"trace[1696283639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"143.647465ms","start":"2026-02-18T22:56:31.907603Z","end":"2026-02-18T22:56:32.051251Z","steps":["trace[1696283639] 'agreement among raft nodes before linearized reading' (duration: 143.493242ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:32.051483Z","caller":"traceutil/trace.go:171","msg":"trace[595115531] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"296.225819ms","start":"2026-02-18T22:56:31.755252Z","end":"2026-02-18T22:56:32.051478Z","steps":["trace[595115531] 'process raft request' (duration: 295.678797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:34.127605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.217775ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T22:56:42.565092Z","caller":"traceutil/trace.go:171","msg":"trace[128548048] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"154.599237ms","start":"2026-02-18T22:56:42.410473Z","end":"2026-02-18T22:56:42.565073Z","steps":["trace[128548048] 'process raft request' (duration: 154.493524ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:42.779695Z","caller":"traceutil/trace.go:171","msg":"trace[2050254025] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"366.591328ms","start":"2026-02-18T22:56:42.413087Z","end":"2026-02-18T22:56:42.779679Z","steps":["trace[2050254025] 'process raft request' (duration: 366.411625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:42.779789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:42.413064Z","time spent":"366.672451ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:42.779969Z","caller":"traceutil/trace.go:171","msg":"trace[1316226037] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"366.317594ms","start":"2026-02-18T22:56:42.413646Z","end":"2026-02-18T22:56:42.779964Z","steps":["trace[1316226037] 'process raft request' (duration: 365.934085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:42.779995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:42.413639Z","time spent":"366.341445ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:46.793145Z","caller":"traceutil/trace.go:171","msg":"trace[1624268538] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"215.537808ms","start":"2026-02-18T22:56:46.577561Z","end":"2026-02-18T22:56:46.793099Z","steps":["trace[1624268538] 'process raft request' (duration: 215.359664ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:48.498682Z","caller":"traceutil/trace.go:171","msg":"trace[1103537108] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"109.649814ms","start":"2026-02-18T22:56:48.389009Z","end":"2026-02-18T22:56:48.498658Z","steps":["trace[1103537108] 'read index received' (duration: 109.443229ms)","trace[1103537108] 'applied index is now lower than readState.Index' (duration: 205.695µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:48.498982Z","caller":"traceutil/trace.go:171","msg":"trace[412418136] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"142.828637ms","start":"2026-02-18T22:56:48.356142Z","end":"2026-02-18T22:56:48.498971Z","steps":["trace[412418136] 'process raft request' (duration: 142.369528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:48.499197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.173015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:48.499231Z","caller":"traceutil/trace.go:171","msg":"trace[1566645446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"110.254986ms","start":"2026-02-18T22:56:48.388967Z","end":"2026-02-18T22:56:48.499222Z","steps":["trace[1566645446] 'agreement among raft nodes before linearized reading' (duration: 110.190915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:48.739246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.889348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:48.739314Z","caller":"traceutil/trace.go:171","msg":"trace[1709909245] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"234.99104ms","start":"2026-02-18T22:56:48.504311Z","end":"2026-02-18T22:56:48.739302Z","steps":["trace[1709909245] 'process raft request' (duration: 108.992479ms)","trace[1709909245] 'compare' (duration: 125.749955ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:48.975857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.712627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:48.975934Z","caller":"traceutil/trace.go:171","msg":"trace[1676544653] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"142.109501ms","start":"2026-02-18T22:56:48.833814Z","end":"2026-02-18T22:56:48.975923Z","steps":["trace[1676544653] 'compare' (duration: 136.631546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.250154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.892043ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:49.250246Z","caller":"traceutil/trace.go:171","msg":"trace[57541243] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:760; }","duration":"146.993755ms","start":"2026-02-18T22:56:49.103241Z","end":"2026-02-18T22:56:49.250234Z","steps":["trace[57541243] 'range keys from in-memory index tree' (duration: 146.879432ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:49.250455Z","caller":"traceutil/trace.go:171","msg":"trace[1363789565] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"270.287817ms","start":"2026-02-18T22:56:48.980157Z","end":"2026-02-18T22:56:49.250445Z","steps":["trace[1363789565] 'process raft request' (duration: 200.525809ms)","trace[1363789565] 'compare' (duration: 69.697517ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:49.977736Z","caller":"traceutil/trace.go:171","msg":"trace[1677877501] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"159.920139ms","start":"2026-02-18T22:56:49.817801Z","end":"2026-02-18T22:56:49.977721Z","steps":["trace[1677877501] 'read index received' (duration: 159.820207ms)","trace[1677877501] 'applied index is now lower than readState.Index' (duration: 99.402µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:49.977827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.006861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:49.977848Z","caller":"traceutil/trace.go:171","msg":"trace[294964287] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:763; }","duration":"160.065013ms","start":"2026-02-18T22:56:49.817776Z","end":"2026-02-18T22:56:49.977841Z","steps":["trace[294964287] 'agreement among raft nodes before linearized reading' (duration: 160.001181ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:49.977992Z","caller":"traceutil/trace.go:171","msg":"trace[815787832] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"438.916892ms","start":"2026-02-18T22:56:49.539056Z","end":"2026-02-18T22:56:49.977973Z","steps":["trace[815787832] 'process raft request' (duration: 438.576715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.978094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:49.539039Z","time spent":"438.997144ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:50.884914Z","caller":"traceutil/trace.go:171","msg":"trace[1079256065] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"141.163733ms","start":"2026-02-18T22:56:50.743726Z","end":"2026-02-18T22:56:50.88489Z","steps":["trace[1079256065] 'read index received' (duration: 141.000439ms)","trace[1079256065] 'applied index is now lower than readState.Index' (duration: 162.594µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:50.88752Z","caller":"traceutil/trace.go:171","msg":"trace[114871401] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"378.943441ms","start":"2026-02-18T22:56:50.508563Z","end":"2026-02-18T22:56:50.887506Z","steps":["trace[114871401] 'process raft request' (duration: 376.208413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:50.890896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:50.508546Z","time spent":"379.005363ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:50.891514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.783073ms","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-18T22:56:50.891547Z","caller":"traceutil/trace.go:171","msg":"trace[991784804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"147.846983ms","start":"2026-02-18T22:56:50.743692Z","end":"2026-02-18T22:56:50.891539Z","steps":["trace[991784804] 'agreement among raft nodes before linearized reading' (duration: 147.715211ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:51.029897Z","caller":"traceutil/trace.go:171","msg":"trace[2068722545] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"144.914971ms","start":"2026-02-18T22:56:50.884962Z","end":"2026-02-18T22:56:51.029877Z","steps":["trace[2068722545] 'read index received' (duration: 53.34006ms)","trace[2068722545] 'applied index is now lower than readState.Index' (duration: 91.574301ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:51.030119Z","caller":"traceutil/trace.go:171","msg":"trace[59376731] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"280.7564ms","start":"2026-02-18T22:56:50.749346Z","end":"2026-02-18T22:56:51.030103Z","steps":["trace[59376731] 'process raft request' (duration: 188.948324ms)","trace[59376731] 'compare' (duration: 91.496199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:51.030324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.390081ms","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-18T22:56:51.030356Z","caller":"traceutil/trace.go:171","msg":"trace[968017087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"196.453183ms","start":"2026-02-18T22:56:50.833886Z","end":"2026-02-18T22:56:51.030339Z","steps":["trace[968017087] 'agreement among raft nodes before linearized reading' (duration: 196.356211ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:51.03566Z","caller":"traceutil/trace.go:171","msg":"trace[1870818708] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"138.492146ms","start":"2026-02-18T22:56:50.897159Z","end":"2026-02-18T22:56:51.035651Z","steps":["trace[1870818708] 'process raft request' (duration: 138.440705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:51.361527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.375205ms","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-18T22:56:51.361617Z","caller":"traceutil/trace.go:171","msg":"trace[994134522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"107.514499ms","start":"2026-02-18T22:56:51.254085Z","end":"2026-02-18T22:56:51.3616Z","steps":["trace[994134522] 'range keys from in-memory index tree' (duration: 107.231282ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:01.375847Z","caller":"traceutil/trace.go:171","msg":"trace[1233078508] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"304.717689ms","start":"2026-02-18T22:57:01.071108Z","end":"2026-02-18T22:57:01.375825Z","steps":["trace[1233078508] 'read index received' (duration: 212.561695ms)","trace[1233078508] 'applied index is now lower than readState.Index' (duration: 92.155054ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:01.375939Z","caller":"traceutil/trace.go:171","msg":"trace[613930876] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"307.179161ms","start":"2026-02-18T22:57:01.068749Z","end":"2026-02-18T22:57:01.375929Z","steps":["trace[613930876] 'process raft request' (duration: 214.974397ms)","trace[613930876] 'compare' (duration: 92.00389ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:01.376027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:01.068732Z","time spent":"307.230531ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:01.376074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.952333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:57:01.376119Z","caller":"traceutil/trace.go:171","msg":"trace[480131064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"305.014664ms","start":"2026-02-18T22:57:01.071091Z","end":"2026-02-18T22:57:01.376105Z","steps":["trace[480131064] 'agreement among raft nodes before linearized reading' (duration: 304.882372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:01.376152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:01.071083Z","time spent":"305.059295ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:57:01.403886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.372048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:57:01.403952Z","caller":"traceutil/trace.go:171","msg":"trace[1260743417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"301.464619ms","start":"2026-02-18T22:57:01.102473Z","end":"2026-02-18T22:57:01.403937Z","steps":["trace[1260743417] 'agreement among raft nodes before linearized reading' (duration: 301.318516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:01.404215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:01.102462Z","time spent":"301.738535ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:57:01.563909Z","caller":"traceutil/trace.go:171","msg":"trace[601185515] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"154.002314ms","start":"2026-02-18T22:57:01.409882Z","end":"2026-02-18T22:57:01.563884Z","steps":["trace[601185515] 'process raft request' (duration: 153.936273ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:01.564999Z","caller":"traceutil/trace.go:171","msg":"trace[32486628] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"184.139943ms","start":"2026-02-18T22:57:01.380838Z","end":"2026-02-18T22:57:01.564978Z","steps":["trace[32486628] 'process raft request' (duration: 147.69635ms)","trace[32486628] 'compare' (duration: 34.950342ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:01.565238Z","caller":"traceutil/trace.go:171","msg":"trace[1195244225] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"161.445851ms","start":"2026-02-18T22:57:01.403776Z","end":"2026-02-18T22:57:01.565222Z","steps":["trace[1195244225] 'read index received' (duration: 124.767394ms)","trace[1195244225] 'applied index is now lower than readState.Index' (duration: 36.676067ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:01.565381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.361206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:01.565424Z","caller":"traceutil/trace.go:171","msg":"trace[700158582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"175.446108ms","start":"2026-02-18T22:57:01.389966Z","end":"2026-02-18T22:57:01.565412Z","steps":["trace[700158582] 'agreement among raft nodes before linearized reading' (duration: 175.360416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:18.106983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.124813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:57:18.107093Z","caller":"traceutil/trace.go:171","msg":"trace[174390700] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"291.943397ms","start":"2026-02-18T22:57:17.815132Z","end":"2026-02-18T22:57:18.107076Z","steps":["trace[174390700] 'read index received' (duration: 157.627671ms)","trace[174390700] 'applied index is now lower than readState.Index' (duration: 134.314566ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:18.107176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.038659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:18.107205Z","caller":"traceutil/trace.go:171","msg":"trace[1040280241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"292.08841ms","start":"2026-02-18T22:57:17.815107Z","end":"2026-02-18T22:57:18.107195Z","steps":["trace[1040280241] 'agreement among raft nodes before linearized reading' (duration: 292.016038ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:18.107498Z","caller":"traceutil/trace.go:171","msg":"trace[284897401] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"357.106888ms","start":"2026-02-18T22:57:17.750378Z","end":"2026-02-18T22:57:18.107485Z","steps":["trace[284897401] 'process raft request' (duration: 222.415713ms)","trace[284897401] 'compare' (duration: 134.031191ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:18.107559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:17.750368Z","time spent":"357.156889ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:19.913374Z","caller":"traceutil/trace.go:171","msg":"trace[617548390] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"123.39515ms","start":"2026-02-18T22:57:19.789964Z","end":"2026-02-18T22:57:19.913359Z","steps":["trace[617548390] 'read index received' (duration: 123.275208ms)","trace[617548390] 'applied index is now lower than readState.Index' (duration: 119.412µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:19.913525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.579124ms","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-18T22:57:19.913541Z","caller":"traceutil/trace.go:171","msg":"trace[602272314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"123.622786ms","start":"2026-02-18T22:57:19.789913Z","end":"2026-02-18T22:57:19.913536Z","steps":["trace[602272314] 'agreement among raft nodes before linearized reading' (duration: 123.506783ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:19.91371Z","caller":"traceutil/trace.go:171","msg":"trace[433958156] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"140.748721ms","start":"2026-02-18T22:57:19.772956Z","end":"2026-02-18T22:57:19.913705Z","steps":["trace[433958156] 'process raft request' (duration: 140.324252ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:20.068571Z","caller":"traceutil/trace.go:171","msg":"trace[1317456197] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"151.90186ms","start":"2026-02-18T22:57:19.916656Z","end":"2026-02-18T22:57:20.068558Z","steps":["trace[1317456197] 'process raft request' (duration: 151.820479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:21.34153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.629405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:57:21.341586Z","caller":"traceutil/trace.go:171","msg":"trace[754597212] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:913; }","duration":"981.710836ms","start":"2026-02-18T22:57:20.359856Z","end":"2026-02-18T22:57:21.341567Z","steps":["trace[754597212] 'count revisions from in-memory index tree' (duration: 981.580214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:21.341613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:20.359843Z","time spent":"981.762348ms","remote":"127.0.0.1:40534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:57:21.341718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.746826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:21.341732Z","caller":"traceutil/trace.go:171","msg":"trace[2003374541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"951.759266ms","start":"2026-02-18T22:57:20.389967Z","end":"2026-02-18T22:57:21.341726Z","steps":["trace[2003374541] 'range keys from in-memory index tree' (duration: 951.666773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:21.341744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:20.389904Z","time spent":"951.837117ms","remote":"127.0.0.1:40372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:57:21.585601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301868417615262,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:57:21.901669Z","caller":"traceutil/trace.go:171","msg":"trace[1182035658] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"1.180564882s","start":"2026-02-18T22:57:20.721086Z","end":"2026-02-18T22:57:21.901651Z","steps":["trace[1182035658] 'process raft request' (duration: 1.180440129s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:21.901774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:20.721072Z","time spent":"1.180648293s","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:21.9019Z","caller":"traceutil/trace.go:171","msg":"trace[902462708] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:950; }","duration":"816.326541ms","start":"2026-02-18T22:57:21.085562Z","end":"2026-02-18T22:57:21.901889Z","steps":["trace[902462708] 'read index received' (duration: 816.321301ms)","trace[902462708] 'applied index is now lower than readState.Index' (duration: 4.26µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:21.902031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.464044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T22:57:21.902052Z","caller":"traceutil/trace.go:171","msg":"trace[397969968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:914; }","duration":"816.525265ms","start":"2026-02-18T22:57:21.08552Z","end":"2026-02-18T22:57:21.902046Z","steps":["trace[397969968] 'agreement among raft nodes before linearized reading' (duration: 816.434783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:21.902072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:21.085486Z","time spent":"816.581656ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T22:57:22.068573Z","caller":"traceutil/trace.go:171","msg":"trace[1392693457] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"163.205522ms","start":"2026-02-18T22:57:21.9053Z","end":"2026-02-18T22:57:22.068505Z","steps":["trace[1392693457] 'process raft request' (duration: 163.165251ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:22.068876Z","caller":"traceutil/trace.go:171","msg":"trace[539675190] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"251.017211ms","start":"2026-02-18T22:57:21.817846Z","end":"2026-02-18T22:57:22.068863Z","steps":["trace[539675190] 'process raft request' (duration: 151.844539ms)","trace[539675190] 'compare' (duration: 98.679442ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:22.068996Z","caller":"traceutil/trace.go:171","msg":"trace[1815201122] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"167.055314ms","start":"2026-02-18T22:57:21.90193Z","end":"2026-02-18T22:57:22.068985Z","steps":["trace[1815201122] 'read index received' (duration: 67.76645ms)","trace[1815201122] 'applied index is now lower than readState.Index' (duration: 99.286164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:22.069263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.988074ms","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-18T22:57:22.069296Z","caller":"traceutil/trace.go:171","msg":"trace[1255241969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:916; }","duration":"518.053965ms","start":"2026-02-18T22:57:21.551231Z","end":"2026-02-18T22:57:22.069285Z","steps":["trace[1255241969] 'agreement among raft nodes before linearized reading' (duration: 517.922682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:22.069324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:21.551216Z","time spent":"518.099507ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T22:57:22.069444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.403972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:22.069491Z","caller":"traceutil/trace.go:171","msg":"trace[901844805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"725.473103ms","start":"2026-02-18T22:57:21.34401Z","end":"2026-02-18T22:57:22.069483Z","steps":["trace[901844805] 'agreement among raft nodes before linearized reading' (duration: 725.415162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:22.069516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:21.344Z","time spent":"725.509514ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:57:22.069734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.693657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:57:22.069762Z","caller":"traceutil/trace.go:171","msg":"trace[1024316096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:916; }","duration":"152.742689ms","start":"2026-02-18T22:57:21.91701Z","end":"2026-02-18T22:57:22.069753Z","steps":["trace[1024316096] 'agreement among raft nodes before linearized reading' (duration: 152.646867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:22.305226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.220279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:57:22.305375Z","caller":"traceutil/trace.go:171","msg":"trace[492553885] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:952; }","duration":"218.220839ms","start":"2026-02-18T22:57:22.087143Z","end":"2026-02-18T22:57:22.305364Z","steps":["trace[492553885] 'read index received' (duration: 107.816917ms)","trace[492553885] 'applied index is now lower than readState.Index' (duration: 110.403192ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:22.30551Z","caller":"traceutil/trace.go:171","msg":"trace[1834563236] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"226.043407ms","start":"2026-02-18T22:57:22.079459Z","end":"2026-02-18T22:57:22.305503Z","steps":["trace[1834563236] 'process raft request' (duration: 115.492191ms)","trace[1834563236] 'compare' (duration: 110.144038ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:22.305616Z","caller":"traceutil/trace.go:171","msg":"trace[1014962194] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"219.885486ms","start":"2026-02-18T22:57:22.085725Z","end":"2026-02-18T22:57:22.30561Z","steps":["trace[1014962194] 'process raft request' (duration: 219.566409ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:22.30574Z","caller":"traceutil/trace.go:171","msg":"trace[784125948] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"219.978027ms","start":"2026-02-18T22:57:22.085757Z","end":"2026-02-18T22:57:22.305735Z","steps":["trace[784125948] 'process raft request' (duration: 219.583959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:22.305814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.668469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:22.30583Z","caller":"traceutil/trace.go:171","msg":"trace[2020910735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"218.70291ms","start":"2026-02-18T22:57:22.087122Z","end":"2026-02-18T22:57:22.305825Z","steps":["trace[2020910735] 'agreement among raft nodes before linearized reading' (duration: 218.67452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:22.305971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.935663ms","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-18T22:57:22.305988Z","caller":"traceutil/trace.go:171","msg":"trace[2077349255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"117.978564ms","start":"2026-02-18T22:57:22.188005Z","end":"2026-02-18T22:57:22.305983Z","steps":["trace[2077349255] 'agreement among raft nodes before linearized reading' (duration: 117.907193ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:22.478722Z","caller":"traceutil/trace.go:171","msg":"trace[1718489907] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"167.569146ms","start":"2026-02-18T22:57:22.311124Z","end":"2026-02-18T22:57:22.478693Z","steps":["trace[1718489907] 'process raft request' (duration: 134.285824ms)","trace[1718489907] 'compare' (duration: 33.093588ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:22.934325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.050322ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T22:57:22.934411Z","caller":"traceutil/trace.go:171","msg":"trace[2029884536] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"244.753047ms","start":"2026-02-18T22:57:22.689647Z","end":"2026-02-18T22:57:22.9344Z","steps":["trace[2029884536] 'read index received' (duration: 43.587553ms)","trace[2029884536] 'applied index is now lower than readState.Index' (duration: 201.164594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:22.934455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.800378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:22.934469Z","caller":"traceutil/trace.go:171","msg":"trace[1968696063] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:920; }","duration":"244.855869ms","start":"2026-02-18T22:57:22.689608Z","end":"2026-02-18T22:57:22.934464Z","steps":["trace[1968696063] 'agreement among raft nodes before linearized reading' (duration: 244.817608ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:24.468118Z","caller":"traceutil/trace.go:171","msg":"trace[1326106663] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"157.871428ms","start":"2026-02-18T22:57:24.310233Z","end":"2026-02-18T22:57:24.468105Z","steps":["trace[1326106663] 'read index received' (duration: 157.740345ms)","trace[1326106663] 'applied index is now lower than readState.Index' (duration: 130.533µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:24.468266Z","caller":"traceutil/trace.go:171","msg":"trace[484139942] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"387.664494ms","start":"2026-02-18T22:57:24.080596Z","end":"2026-02-18T22:57:24.46826Z","steps":["trace[484139942] 'process raft request' (duration: 387.430899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:24.468329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:24.080579Z","time spent":"387.706286ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:24.468456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.223895ms","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-18T22:57:24.468471Z","caller":"traceutil/trace.go:171","msg":"trace[476940049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"158.264186ms","start":"2026-02-18T22:57:24.310203Z","end":"2026-02-18T22:57:24.468467Z","steps":["trace[476940049] 'agreement among raft nodes before linearized reading' (duration: 158.206425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:24.46855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.595672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:57:24.468561Z","caller":"traceutil/trace.go:171","msg":"trace[39903005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"157.625032ms","start":"2026-02-18T22:57:24.310933Z","end":"2026-02-18T22:57:24.468558Z","steps":["trace[39903005] 'agreement among raft nodes before linearized reading' (duration: 157.591172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:24.468728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.197584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:57:24.468741Z","caller":"traceutil/trace.go:171","msg":"trace[1996134898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"157.219094ms","start":"2026-02-18T22:57:24.311518Z","end":"2026-02-18T22:57:24.468737Z","steps":["trace[1996134898] 'agreement among raft nodes before linearized reading' (duration: 157.176333ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:32.378541Z","caller":"traceutil/trace.go:171","msg":"trace[1572131755] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"130.153626ms","start":"2026-02-18T22:57:32.248371Z","end":"2026-02-18T22:57:32.378524Z","steps":["trace[1572131755] 'process raft request' (duration: 130.049994ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:34.726403Z","caller":"traceutil/trace.go:171","msg":"trace[1922232280] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"113.904578ms","start":"2026-02-18T22:57:34.612485Z","end":"2026-02-18T22:57:34.726389Z","steps":["trace[1922232280] 'read index received' (duration: 67.819972ms)","trace[1922232280] 'applied index is now lower than readState.Index' (duration: 46.084006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:34.726554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.055651ms","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-18T22:57:34.72657Z","caller":"traceutil/trace.go:171","msg":"trace[656307410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"114.094511ms","start":"2026-02-18T22:57:34.61247Z","end":"2026-02-18T22:57:34.726565Z","steps":["trace[656307410] 'agreement among raft nodes before linearized reading' (duration: 113.971499ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:34.726742Z","caller":"traceutil/trace.go:171","msg":"trace[1316185330] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"115.008391ms","start":"2026-02-18T22:57:34.611727Z","end":"2026-02-18T22:57:34.726735Z","steps":["trace[1316185330] 'process raft request' (duration: 68.620559ms)","trace[1316185330] 'compare' (duration: 45.846951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:35.511633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.810537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:57:35.511689Z","caller":"traceutil/trace.go:171","msg":"trace[1722497553] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"122.108573ms","start":"2026-02-18T22:57:35.389572Z","end":"2026-02-18T22:57:35.51168Z","steps":["trace[1722497553] 'read index received' (duration: 22.181µs)","trace[1722497553] 'applied index is now lower than readState.Index' (duration: 122.085762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:35.511728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.155734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:35.511738Z","caller":"traceutil/trace.go:171","msg":"trace[1759119278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"122.221156ms","start":"2026-02-18T22:57:35.389513Z","end":"2026-02-18T22:57:35.511735Z","steps":["trace[1759119278] 'agreement among raft nodes before linearized reading' (duration: 122.190025ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:35.511877Z","caller":"traceutil/trace.go:171","msg":"trace[592107997] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"736.903938ms","start":"2026-02-18T22:57:34.774967Z","end":"2026-02-18T22:57:35.511871Z","steps":["trace[592107997] 'process raft request' (duration: 170.813175ms)","trace[592107997] 'compare' (duration: 565.737645ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:35.511905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:34.774952Z","time spent":"736.937838ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:36.709297Z","caller":"traceutil/trace.go:171","msg":"trace[1343483805] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"111.140638ms","start":"2026-02-18T22:57:36.59814Z","end":"2026-02-18T22:57:36.70928Z","steps":["trace[1343483805] 'read index received' (duration: 111.020066ms)","trace[1343483805] 'applied index is now lower than readState.Index' (duration: 119.692µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:36.70949Z","caller":"traceutil/trace.go:171","msg":"trace[1171141478] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"147.69594ms","start":"2026-02-18T22:57:36.561788Z","end":"2026-02-18T22:57:36.709484Z","steps":["trace[1171141478] 'process raft request' (duration: 147.408814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:36.709658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.506626ms","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-18T22:57:36.709682Z","caller":"traceutil/trace.go:171","msg":"trace[965111683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"111.560337ms","start":"2026-02-18T22:57:36.598114Z","end":"2026-02-18T22:57:36.709674Z","steps":["trace[965111683] 'agreement among raft nodes before linearized reading' (duration: 111.472855ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:38.63337Z","caller":"traceutil/trace.go:171","msg":"trace[243151866] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"244.330158ms","start":"2026-02-18T22:57:38.389023Z","end":"2026-02-18T22:57:38.633353Z","steps":["trace[243151866] 'read index received' (duration: 244.145665ms)","trace[243151866] 'applied index is now lower than readState.Index' (duration: 183.953µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:38.633476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.42634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:38.6335Z","caller":"traceutil/trace.go:171","msg":"trace[957427715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"244.492961ms","start":"2026-02-18T22:57:38.388996Z","end":"2026-02-18T22:57:38.633489Z","steps":["trace[957427715] 'agreement among raft nodes before linearized reading' (duration: 244.4225ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:38.633792Z","caller":"traceutil/trace.go:171","msg":"trace[214256865] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"552.32581ms","start":"2026-02-18T22:57:38.08146Z","end":"2026-02-18T22:57:38.633786Z","steps":["trace[214256865] 'process raft request' (duration: 551.791117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:38.633851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:38.081449Z","time spent":"552.36201ms","remote":"127.0.0.1:40550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10601,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:38.879794Z","caller":"traceutil/trace.go:171","msg":"trace[733200696] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1008; }","duration":"164.54463ms","start":"2026-02-18T22:57:38.715231Z","end":"2026-02-18T22:57:38.879776Z","steps":["trace[733200696] 'read index received' (duration: 164.332976ms)","trace[733200696] 'applied index is now lower than readState.Index' (duration: 210.734µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:38.8802Z","caller":"traceutil/trace.go:171","msg":"trace[192624899] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"224.885453ms","start":"2026-02-18T22:57:38.655303Z","end":"2026-02-18T22:57:38.880188Z","steps":["trace[192624899] 'process raft request' (duration: 224.30933ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:38.883834Z","caller":"traceutil/trace.go:171","msg":"trace[110142418] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"225.344182ms","start":"2026-02-18T22:57:38.658476Z","end":"2026-02-18T22:57:38.88382Z","steps":["trace[110142418] 'process raft request' (duration: 221.255855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:38.884857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.619479ms","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-18T22:57:38.889103Z","caller":"traceutil/trace.go:171","msg":"trace[918085372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"173.898531ms","start":"2026-02-18T22:57:38.715194Z","end":"2026-02-18T22:57:38.889092Z","steps":["trace[918085372] 'agreement among raft nodes before linearized reading' (duration: 169.592139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:38.886883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.704018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-18T22:57:38.887102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.851359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-18T22:57:38.887146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.952193ms","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-18T22:57:38.89093Z","caller":"traceutil/trace.go:171","msg":"trace[2068723740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"170.777295ms","start":"2026-02-18T22:57:38.720144Z","end":"2026-02-18T22:57:38.890921Z","steps":["trace[2068723740] 'agreement among raft nodes before linearized reading' (duration: 166.690098ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:38.891551Z","caller":"traceutil/trace.go:171","msg":"trace[336143656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:969; }","duration":"141.318785ms","start":"2026-02-18T22:57:38.750223Z","end":"2026-02-18T22:57:38.891542Z","steps":["trace[336143656] 'agreement among raft nodes before linearized reading' (duration: 136.842079ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:38.891693Z","caller":"traceutil/trace.go:171","msg":"trace[1391561210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"171.502051ms","start":"2026-02-18T22:57:38.720184Z","end":"2026-02-18T22:57:38.891686Z","steps":["trace[1391561210] 'agreement among raft nodes before linearized reading' (duration: 166.932103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:39.072884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.626357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:57:39.07303Z","caller":"traceutil/trace.go:171","msg":"trace[319199633] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"165.833158ms","start":"2026-02-18T22:57:38.907185Z","end":"2026-02-18T22:57:39.073018Z","steps":["trace[319199633] 'process raft request' (duration: 165.811348ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:39.073231Z","caller":"traceutil/trace.go:171","msg":"trace[2110832641] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"166.074104ms","start":"2026-02-18T22:57:38.907151Z","end":"2026-02-18T22:57:39.073225Z","steps":["trace[2110832641] 'process raft request' (duration: 165.828098ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:39.073186Z","caller":"traceutil/trace.go:171","msg":"trace[492124706] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"166.500132ms","start":"2026-02-18T22:57:38.906657Z","end":"2026-02-18T22:57:39.073157Z","steps":["trace[492124706] 'process raft request' (duration: 166.297078ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:39.073404Z","caller":"traceutil/trace.go:171","msg":"trace[179676883] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"176.503597ms","start":"2026-02-18T22:57:38.896891Z","end":"2026-02-18T22:57:39.073394Z","steps":["trace[179676883] 'process raft request' (duration: 29.312457ms)","trace[179676883] 'compare' (duration: 146.531375ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:39.87516Z","caller":"traceutil/trace.go:171","msg":"trace[1569927181] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"268.459475ms","start":"2026-02-18T22:57:39.606683Z","end":"2026-02-18T22:57:39.875142Z","steps":["trace[1569927181] 'read index received' (duration: 268.279751ms)","trace[1569927181] 'applied index is now lower than readState.Index' (duration: 179.074µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:39.875231Z","caller":"traceutil/trace.go:171","msg":"trace[1927883226] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"309.579575ms","start":"2026-02-18T22:57:39.565645Z","end":"2026-02-18T22:57:39.875225Z","steps":["trace[1927883226] 'process raft request' (duration: 309.35794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:39.875298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:39.565636Z","time spent":"309.612875ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:39.87544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.758461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:39.875461Z","caller":"traceutil/trace.go:171","msg":"trace[1721160442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"268.803031ms","start":"2026-02-18T22:57:39.606652Z","end":"2026-02-18T22:57:39.875455Z","steps":["trace[1721160442] 'agreement among raft nodes before linearized reading' (duration: 268.772041ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:40.32797Z","caller":"traceutil/trace.go:171","msg":"trace[1650574740] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"444.823297ms","start":"2026-02-18T22:57:39.88313Z","end":"2026-02-18T22:57:40.327953Z","steps":["trace[1650574740] 'process raft request' (duration: 444.699995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:40.328077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:39.883104Z","time spent":"444.91709ms","remote":"127.0.0.1:40544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4865,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:40.703792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.402536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:57:40.703877Z","caller":"traceutil/trace.go:171","msg":"trace[2083180952] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"313.075549ms","start":"2026-02-18T22:57:40.390787Z","end":"2026-02-18T22:57:40.703862Z","steps":["trace[2083180952] 'read index received' (duration: 163.494178ms)","trace[2083180952] 'applied index is now lower than readState.Index' (duration: 149.580171ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:40.703966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.182511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:40.703992Z","caller":"traceutil/trace.go:171","msg":"trace[1584172079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"313.250243ms","start":"2026-02-18T22:57:40.390733Z","end":"2026-02-18T22:57:40.703983Z","steps":["trace[1584172079] 'agreement among raft nodes before linearized reading' (duration: 313.173011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:40.704023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:40.3907Z","time spent":"313.316835ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:57:40.704411Z","caller":"traceutil/trace.go:171","msg":"trace[1670320034] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"350.633903ms","start":"2026-02-18T22:57:40.353759Z","end":"2026-02-18T22:57:40.704393Z","steps":["trace[1670320034] 'process raft request' (duration: 200.562351ms)","trace[1670320034] 'compare' (duration: 149.174562ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:40.704479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:40.353743Z","time spent":"350.700784ms","remote":"127.0.0.1:40764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:41.589661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301868417615434,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:57:41.610272Z","caller":"traceutil/trace.go:171","msg":"trace[1744288277] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"522.071431ms","start":"2026-02-18T22:57:41.088184Z","end":"2026-02-18T22:57:41.610255Z","steps":["trace[1744288277] 'process raft request' (duration: 521.878717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:41.610389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:41.088173Z","time spent":"522.149492ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:41.649544Z","caller":"traceutil/trace.go:171","msg":"trace[846661663] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"560.557984ms","start":"2026-02-18T22:57:41.088969Z","end":"2026-02-18T22:57:41.649527Z","steps":["trace[846661663] 'read index received' (duration: 521.304294ms)","trace[846661663] 'applied index is now lower than readState.Index' (duration: 39.25294ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:41.649614Z","caller":"traceutil/trace.go:171","msg":"trace[979655114] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"561.29221ms","start":"2026-02-18T22:57:41.088317Z","end":"2026-02-18T22:57:41.649609Z","steps":["trace[979655114] 'process raft request' (duration: 561.125076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:41.649697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:41.08831Z","time spent":"561.31934ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:41.649822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.85002ms","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-18T22:57:41.649838Z","caller":"traceutil/trace.go:171","msg":"trace[1282420198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"560.877762ms","start":"2026-02-18T22:57:41.088955Z","end":"2026-02-18T22:57:41.649833Z","steps":["trace[1282420198] 'agreement among raft nodes before linearized reading' (duration: 560.81278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:41.649854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:41.088949Z","time spent":"560.901922ms","remote":"127.0.0.1:40636","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-18T22:57:41.64991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.094555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:57:41.649922Z","caller":"traceutil/trace.go:171","msg":"trace[641201888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"560.117345ms","start":"2026-02-18T22:57:41.089801Z","end":"2026-02-18T22:57:41.649918Z","steps":["trace[641201888] 'agreement among raft nodes before linearized reading' (duration: 560.085635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:41.649933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:41.089795Z","time spent":"560.135586ms","remote":"127.0.0.1:40636","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-18T22:57:41.650051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.457042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:41.650112Z","caller":"traceutil/trace.go:171","msg":"trace[245238809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"259.561483ms","start":"2026-02-18T22:57:41.390535Z","end":"2026-02-18T22:57:41.650096Z","steps":["trace[245238809] 'agreement among raft nodes before linearized reading' (duration: 259.467811ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:41.789166Z","caller":"traceutil/trace.go:171","msg":"trace[86044600] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"133.863245ms","start":"2026-02-18T22:57:41.655275Z","end":"2026-02-18T22:57:41.789138Z","steps":["trace[86044600] 'process raft request' (duration: 69.62805ms)","trace[86044600] 'compare' (duration: 64.123612ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:41.996276Z","caller":"traceutil/trace.go:171","msg":"trace[2122076640] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"117.892033ms","start":"2026-02-18T22:57:41.87837Z","end":"2026-02-18T22:57:41.996262Z","steps":["trace[2122076640] 'read index received' (duration: 117.806091ms)","trace[2122076640] 'applied index is now lower than readState.Index' (duration: 85.172µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:41.996404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.018785ms","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-18T22:57:41.996419Z","caller":"traceutil/trace.go:171","msg":"trace[55983286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"118.069366ms","start":"2026-02-18T22:57:41.878344Z","end":"2026-02-18T22:57:41.996414Z","steps":["trace[55983286] 'agreement among raft nodes before linearized reading' (duration: 117.971684ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:41.996557Z","caller":"traceutil/trace.go:171","msg":"trace[674939661] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"340.190549ms","start":"2026-02-18T22:57:41.656362Z","end":"2026-02-18T22:57:41.996552Z","steps":["trace[674939661] 'process raft request' (duration: 339.820961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:41.9966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:41.656355Z","time spent":"340.213679ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:42.431504Z","caller":"traceutil/trace.go:171","msg":"trace[1641900307] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"198.053597ms","start":"2026-02-18T22:57:42.233429Z","end":"2026-02-18T22:57:42.431482Z","steps":["trace[1641900307] 'process raft request' (duration: 197.606338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:43.051796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.818204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:57:43.051899Z","caller":"traceutil/trace.go:171","msg":"trace[482639508] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"488.36009ms","start":"2026-02-18T22:57:42.563519Z","end":"2026-02-18T22:57:43.051879Z","steps":["trace[482639508] 'process raft request' (duration: 304.369893ms)","trace[482639508] 'compare' (duration: 183.638139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:43.051961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:42.563504Z","time spent":"488.428462ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:43.096797Z","caller":"traceutil/trace.go:171","msg":"trace[752243879] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"402.971822ms","start":"2026-02-18T22:57:42.69381Z","end":"2026-02-18T22:57:43.096781Z","steps":["trace[752243879] 'process raft request' (duration: 402.762158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:43.097446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:42.693793Z","time spent":"403.601856ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:53.627298Z","caller":"traceutil/trace.go:171","msg":"trace[425710437] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"236.246606ms","start":"2026-02-18T22:57:53.391024Z","end":"2026-02-18T22:57:53.62727Z","steps":["trace[425710437] 'read index received' (duration: 236.012721ms)","trace[425710437] 'applied index is now lower than readState.Index' (duration: 232.825µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:53.627637Z","caller":"traceutil/trace.go:171","msg":"trace[1699685811] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"451.993471ms","start":"2026-02-18T22:57:53.175629Z","end":"2026-02-18T22:57:53.627622Z","steps":["trace[1699685811] 'process raft request' (duration: 451.46966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:53.62778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:53.175607Z","time spent":"452.071424ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:53.627992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.964971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:53.628053Z","caller":"traceutil/trace.go:171","msg":"trace[36427341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1056; }","duration":"237.058312ms","start":"2026-02-18T22:57:53.390983Z","end":"2026-02-18T22:57:53.628042Z","steps":["trace[36427341] 'agreement among raft nodes before linearized reading' (duration: 236.976581ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:53.767429Z","caller":"traceutil/trace.go:171","msg":"trace[912131949] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"140.027286ms","start":"2026-02-18T22:57:53.627379Z","end":"2026-02-18T22:57:53.767407Z","steps":["trace[912131949] 'read index received' (duration: 50.196284ms)","trace[912131949] 'applied index is now lower than readState.Index' (duration: 89.830012ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:53.767565Z","caller":"traceutil/trace.go:171","msg":"trace[513489334] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"295.521934ms","start":"2026-02-18T22:57:53.472029Z","end":"2026-02-18T22:57:53.767551Z","steps":["trace[513489334] 'process raft request' (duration: 205.597849ms)","trace[513489334] 'compare' (duration: 89.663679ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:53.767703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.383129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:53.767762Z","caller":"traceutil/trace.go:171","msg":"trace[1078085454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"137.471082ms","start":"2026-02-18T22:57:53.630276Z","end":"2026-02-18T22:57:53.767747Z","steps":["trace[1078085454] 'agreement among raft nodes before linearized reading' (duration: 137.38339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:53.767979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.965096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-18T22:57:53.768009Z","caller":"traceutil/trace.go:171","msg":"trace[1984248796] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1057; }","duration":"277.010878ms","start":"2026-02-18T22:57:53.490987Z","end":"2026-02-18T22:57:53.767997Z","steps":["trace[1984248796] 'agreement among raft nodes before linearized reading' (duration: 276.920726ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:54.178107Z","caller":"traceutil/trace.go:171","msg":"trace[1504170962] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"402.820469ms","start":"2026-02-18T22:57:53.775266Z","end":"2026-02-18T22:57:54.178087Z","steps":["trace[1504170962] 'process raft request' (duration: 330.662205ms)","trace[1504170962] 'compare' (duration: 71.212704ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:54.179401Z","caller":"traceutil/trace.go:171","msg":"trace[2095116999] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1101; }","duration":"401.063622ms","start":"2026-02-18T22:57:53.778324Z","end":"2026-02-18T22:57:54.179387Z","steps":["trace[2095116999] 'read index received' (duration: 327.625401ms)","trace[2095116999] 'applied index is now lower than readState.Index' (duration: 73.437621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:54.179535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.192785ms","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-18T22:57:54.183028Z","caller":"traceutil/trace.go:171","msg":"trace[391067972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"404.706129ms","start":"2026-02-18T22:57:53.778308Z","end":"2026-02-18T22:57:54.183014Z","steps":["trace[391067972] 'agreement among raft nodes before linearized reading' (duration: 401.121413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:54.183099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:53.7783Z","time spent":"404.75643ms","remote":"127.0.0.1:40636","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":"info","ts":"2026-02-18T22:57:54.179598Z","caller":"traceutil/trace.go:171","msg":"trace[1372380296] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"404.142907ms","start":"2026-02-18T22:57:53.775445Z","end":"2026-02-18T22:57:54.179588Z","steps":["trace[1372380296] 'process raft request' (duration: 403.883022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:54.183352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:53.775435Z","time spent":"407.891908ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:54.18277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:53.775245Z","time spent":"407.443319ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:54.184593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.751766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:57:54.184626Z","caller":"traceutil/trace.go:171","msg":"trace[2137551590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"136.787377ms","start":"2026-02-18T22:57:54.047832Z","end":"2026-02-18T22:57:54.184619Z","steps":["trace[2137551590] 'agreement among raft nodes before linearized reading' (duration: 136.678755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:54.185394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.3012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T22:57:54.185444Z","caller":"traceutil/trace.go:171","msg":"trace[2050242481] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1059; }","duration":"239.395682ms","start":"2026-02-18T22:57:53.94604Z","end":"2026-02-18T22:57:54.185436Z","steps":["trace[2050242481] 'agreement among raft nodes before linearized reading' (duration: 239.259139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:54.187433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.765378ms","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-18T22:57:54.187531Z","caller":"traceutil/trace.go:171","msg":"trace[1058561640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"337.900811ms","start":"2026-02-18T22:57:53.849618Z","end":"2026-02-18T22:57:54.187519Z","steps":["trace[1058561640] 'agreement among raft nodes before linearized reading' (duration: 333.66747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:54.187619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:53.849599Z","time spent":"338.012583ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T23:01:07.231764Z","caller":"traceutil/trace.go:171","msg":"trace[416409741] linearizableReadLoop","detail":"{readStateIndex:2055; appliedIndex:2054; }","duration":"102.518398ms","start":"2026-02-18T23:01:07.129229Z","end":"2026-02-18T23:01:07.231747Z","steps":["trace[416409741] 'read index received' (duration: 102.385405ms)","trace[416409741] 'applied index is now lower than readState.Index' (duration: 132.363µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T23:01:07.231937Z","caller":"traceutil/trace.go:171","msg":"trace[1725263206] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"163.226533ms","start":"2026-02-18T23:01:07.068702Z","end":"2026-02-18T23:01:07.231929Z","steps":["trace[1725263206] 'process raft request' (duration: 162.952206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:07.232122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.888447ms","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-18T23:01:07.232143Z","caller":"traceutil/trace.go:171","msg":"trace[1527458279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1972; }","duration":"102.958198ms","start":"2026-02-18T23:01:07.129178Z","end":"2026-02-18T23:01:07.232136Z","steps":["trace[1527458279] 'agreement among raft nodes before linearized reading' (duration: 102.875187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:07.23225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.162388ms","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-18T23:01:07.232283Z","caller":"traceutil/trace.go:171","msg":"trace[1963634235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1972; }","duration":"100.402573ms","start":"2026-02-18T23:01:07.131872Z","end":"2026-02-18T23:01:07.232274Z","steps":["trace[1963634235] 'agreement among raft nodes before linearized reading' (duration: 100.340602ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T23:01:07.659116Z","caller":"traceutil/trace.go:171","msg":"trace[1361126811] linearizableReadLoop","detail":"{readStateIndex:2058; appliedIndex:2055; }","duration":"356.354662ms","start":"2026-02-18T23:01:07.302744Z","end":"2026-02-18T23:01:07.659099Z","steps":["trace[1361126811] 'read index received' (duration: 325.051504ms)","trace[1361126811] 'applied index is now lower than readState.Index' (duration: 31.302658ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T23:01:07.659471Z","caller":"traceutil/trace.go:171","msg":"trace[581502460] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"423.036204ms","start":"2026-02-18T23:01:07.236423Z","end":"2026-02-18T23:01:07.659459Z","steps":["trace[581502460] 'process raft request' (duration: 391.364599ms)","trace[581502460] 'compare' (duration: 30.410238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T23:01:07.659604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T23:01:07.236409Z","time spent":"423.122247ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T23:01:07.659834Z","caller":"traceutil/trace.go:171","msg":"trace[1486077756] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"421.486061ms","start":"2026-02-18T23:01:07.23834Z","end":"2026-02-18T23:01:07.659826Z","steps":["trace[1486077756] 'process raft request' (duration: 420.647713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:07.65991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T23:01:07.23833Z","time spent":"421.543922ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T23:01:07.660079Z","caller":"traceutil/trace.go:171","msg":"trace[1899530392] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"392.992304ms","start":"2026-02-18T23:01:07.267078Z","end":"2026-02-18T23:01:07.660071Z","steps":["trace[1899530392] 'process raft request' (duration: 391.975093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:07.660153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T23:01:07.267052Z","time spent":"393.066465ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T23:01:07.660337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.589858ms","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-18T23:01:07.660403Z","caller":"traceutil/trace.go:171","msg":"trace[644687790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1975; }","duration":"357.68048ms","start":"2026-02-18T23:01:07.302715Z","end":"2026-02-18T23:01:07.660396Z","steps":["trace[644687790] 'agreement among raft nodes before linearized reading' (duration: 357.563788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:07.660457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T23:01:07.302703Z","time spent":"357.747201ms","remote":"127.0.0.1:40636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T23:01:07.660676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.309931ms","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-18T23:01:07.660753Z","caller":"traceutil/trace.go:171","msg":"trace[472670763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1975; }","duration":"289.454645ms","start":"2026-02-18T23:01:07.371291Z","end":"2026-02-18T23:01:07.660745Z","steps":["trace[472670763] 'agreement among raft nodes before linearized reading' (duration: 289.323462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T23:01:07.66125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.209061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T23:01:07.661309Z","caller":"traceutil/trace.go:171","msg":"trace[827658505] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:1975; }","duration":"217.302873ms","start":"2026-02-18T23:01:07.443987Z","end":"2026-02-18T23:01:07.66129Z","steps":["trace[827658505] 'agreement among raft nodes before linearized reading' (duration: 217.16284ms)"],"step_count":1}