{"level":"warn","ts":"2026-02-11T02:24:31.197509Z","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-11T02:24:31.198946Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-11T02:24:31.199095Z","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-11T02:24:31.199154Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-02-11T02:24:31.199289Z","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-11T02:24:31.201018Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-02-11T02:24:31.201361Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-11T02:24:31.209253Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.534185ms"} {"level":"info","ts":"2026-02-11T02:24:31.239219Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-02-11T02:24:31.239277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T02:24:31.239295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-02-11T02:24:31.239303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T02:24:31.239309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-02-11T02:24:31.239336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-02-11T02:24:31.25301Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T02:24:31.256764Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T02:24:31.262903Z","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-11T02:24:31.269505Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T02:24:31.270197Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T02:24:31.270349Z","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-11T02:24:31.270425Z","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-11T02:24:31.27045Z","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-11T02:24:31.270987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-02-11T02:24:31.271162Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-02-11T02:24:31.289723Z","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-11T02:24:31.290116Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T02:24:31.289954Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-02-11T02:24:31.292441Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T02:24:31.29485Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-02-11T02:24:31.647015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T02:24:31.647094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T02:24:31.647114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-02-11T02:24:31.647133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-02-11T02:24:31.647138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-02-11T02:24:31.647147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-02-11T02:24:31.647154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-02-11T02:24:31.656054Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T02:24:31.656091Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T02:24:31.656334Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:24:31.656671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T02:24:31.675307Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T02:24:31.675325Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T02:24:31.676691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-02-11T02:24:31.676969Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:24:31.677021Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:24:31.684091Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T02:24:31.687959Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-11T02:24:54.622928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050629439392429,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T02:24:54.772587Z","caller":"traceutil/trace.go:171","msg":"trace[1100726569] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"650.015493ms","start":"2026-02-11T02:24:54.122556Z","end":"2026-02-11T02:24:54.772572Z","steps":["trace[1100726569] 'read index received' (duration: 649.881929ms)","trace[1100726569] 'applied index is now lower than readState.Index' (duration: 133.014µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:24:54.772664Z","caller":"traceutil/trace.go:171","msg":"trace[87704999] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"798.119378ms","start":"2026-02-11T02:24:53.974539Z","end":"2026-02-11T02:24:54.772658Z","steps":["trace[87704999] 'process raft request' (duration: 797.938334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:24:54.772843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.413033ms","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-11T02:24:54.772944Z","caller":"traceutil/trace.go:171","msg":"trace[179137299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"632.532526ms","start":"2026-02-11T02:24:54.140373Z","end":"2026-02-11T02:24:54.772906Z","steps":["trace[179137299] 'agreement among raft nodes before linearized reading' (duration: 632.391463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:24:54.772978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:24:54.140362Z","time spent":"632.606238ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T02:24:54.773026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:24:53.974521Z","time spent":"798.159458ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:24:54.773126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.579777ms","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-11T02:24:54.773142Z","caller":"traceutil/trace.go:171","msg":"trace[1597683141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:419; }","duration":"228.631479ms","start":"2026-02-11T02:24:54.544505Z","end":"2026-02-11T02:24:54.773136Z","steps":["trace[1597683141] 'agreement among raft nodes before linearized reading' (duration: 228.597118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:24:54.773161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.13383ms","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-11T02:24:54.773198Z","caller":"traceutil/trace.go:171","msg":"trace[919809597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"630.197852ms","start":"2026-02-11T02:24:54.142979Z","end":"2026-02-11T02:24:54.773177Z","steps":["trace[919809597] 'agreement among raft nodes before linearized reading' (duration: 630.155321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:24:54.773221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:24:54.142969Z","time spent":"630.245403ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T02:24:54.773255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.720459ms","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-11T02:24:54.773272Z","caller":"traceutil/trace.go:171","msg":"trace[1417480388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"650.77236ms","start":"2026-02-11T02:24:54.122494Z","end":"2026-02-11T02:24:54.773267Z","steps":["trace[1417480388] 'agreement among raft nodes before linearized reading' (duration: 650.745189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:24:54.773284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:24:54.122476Z","time spent":"650.80495ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T02:24:59.221519Z","caller":"traceutil/trace.go:171","msg":"trace[442892602] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"238.859116ms","start":"2026-02-11T02:24:58.982644Z","end":"2026-02-11T02:24:59.221503Z","steps":["trace[442892602] 'process raft request' (duration: 230.733677ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:24:59.222037Z","caller":"traceutil/trace.go:171","msg":"trace[492406571] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"230.267418ms","start":"2026-02-11T02:24:58.991764Z","end":"2026-02-11T02:24:59.222031Z","steps":["trace[492406571] 'process raft request' (duration: 230.052733ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:24:59.222661Z","caller":"traceutil/trace.go:171","msg":"trace[643894270] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"230.609306ms","start":"2026-02-11T02:24:58.992047Z","end":"2026-02-11T02:24:59.222656Z","steps":["trace[643894270] 'process raft request' (duration: 229.858258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:01.207324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.988475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:25:01.207434Z","caller":"traceutil/trace.go:171","msg":"trace[83175379] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:489; }","duration":"183.86559ms","start":"2026-02-11T02:25:01.023556Z","end":"2026-02-11T02:25:01.207422Z","steps":["trace[83175379] 'read index received' (duration: 29.614348ms)","trace[83175379] 'applied index is now lower than readState.Index' (duration: 154.250722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:01.207493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.936351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-11T02:25:01.207505Z","caller":"traceutil/trace.go:171","msg":"trace[1908349509] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:471; }","duration":"183.949301ms","start":"2026-02-11T02:25:01.023551Z","end":"2026-02-11T02:25:01.207501Z","steps":["trace[1908349509] 'agreement among raft nodes before linearized reading' (duration: 183.89888ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:01.207711Z","caller":"traceutil/trace.go:171","msg":"trace[674784253] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"221.65876ms","start":"2026-02-11T02:25:00.986045Z","end":"2026-02-11T02:25:01.207704Z","steps":["trace[674784253] 'process raft request' (duration: 67.163073ms)","trace[674784253] 'compare' (duration: 153.914644ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:01.207769Z","caller":"traceutil/trace.go:171","msg":"trace[1768673879] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"220.81281ms","start":"2026-02-11T02:25:00.986952Z","end":"2026-02-11T02:25:01.207765Z","steps":["trace[1768673879] 'process raft request' (duration: 220.443091ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:01.487427Z","caller":"traceutil/trace.go:171","msg":"trace[439041348] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"273.876909ms","start":"2026-02-11T02:25:01.213526Z","end":"2026-02-11T02:25:01.487403Z","steps":["trace[439041348] 'process raft request' (duration: 255.308268ms)","trace[439041348] 'compare' (duration: 18.461548ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:05.224099Z","caller":"traceutil/trace.go:171","msg":"trace[539193953] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"203.397362ms","start":"2026-02-11T02:25:05.020688Z","end":"2026-02-11T02:25:05.224086Z","steps":["trace[539193953] 'read index received' (duration: 203.285129ms)","trace[539193953] 'applied index is now lower than readState.Index' (duration: 111.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:05.224238Z","caller":"traceutil/trace.go:171","msg":"trace[1884894703] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"248.444119ms","start":"2026-02-11T02:25:04.975786Z","end":"2026-02-11T02:25:05.224231Z","steps":["trace[1884894703] 'process raft request' (duration: 248.228474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:05.224383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.684409ms","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-11T02:25:05.224398Z","caller":"traceutil/trace.go:171","msg":"trace[490828197] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"203.735909ms","start":"2026-02-11T02:25:05.020657Z","end":"2026-02-11T02:25:05.224393Z","steps":["trace[490828197] 'agreement among raft nodes before linearized reading' (duration: 203.665327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:05.224478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.514733ms","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-11T02:25:05.22449Z","caller":"traceutil/trace.go:171","msg":"trace[901192642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"178.549073ms","start":"2026-02-11T02:25:05.045938Z","end":"2026-02-11T02:25:05.224487Z","steps":["trace[901192642] 'agreement among raft nodes before linearized reading' (duration: 178.512562ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:05.694479Z","caller":"traceutil/trace.go:171","msg":"trace[2074652657] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"461.252341ms","start":"2026-02-11T02:25:05.23321Z","end":"2026-02-11T02:25:05.694462Z","steps":["trace[2074652657] 'process raft request' (duration: 454.910603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:05.694573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:05.2332Z","time spent":"461.325193ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:25:05.695212Z","caller":"traceutil/trace.go:171","msg":"trace[1309144762] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:497; }","duration":"397.499618ms","start":"2026-02-11T02:25:05.297703Z","end":"2026-02-11T02:25:05.695202Z","steps":["trace[1309144762] 'read index received' (duration: 390.426923ms)","trace[1309144762] 'applied index is now lower than readState.Index' (duration: 7.072035ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:05.695244Z","caller":"traceutil/trace.go:171","msg":"trace[1587334907] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"461.887946ms","start":"2026-02-11T02:25:05.233347Z","end":"2026-02-11T02:25:05.695235Z","steps":["trace[1587334907] 'process raft request' (duration: 461.755853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:05.695264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:05.234228Z","time spent":"461.034806ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T02:25:05.695293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:05.233332Z","time spent":"461.934547ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:25:05.695425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.717733ms","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-11T02:25:05.695447Z","caller":"traceutil/trace.go:171","msg":"trace[58569525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"397.761864ms","start":"2026-02-11T02:25:05.297678Z","end":"2026-02-11T02:25:05.695439Z","steps":["trace[58569525] 'agreement among raft nodes before linearized reading' (duration: 397.684192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:05.695465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:05.297665Z","time spent":"397.796625ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:25:05.69549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.862755ms","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-11T02:25:05.695515Z","caller":"traceutil/trace.go:171","msg":"trace[1386029602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"372.901096ms","start":"2026-02-11T02:25:05.322607Z","end":"2026-02-11T02:25:05.695508Z","steps":["trace[1386029602] 'agreement among raft nodes before linearized reading' (duration: 372.818904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:05.695535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:05.3226Z","time spent":"372.928906ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T02:25:11.158996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.384284ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T02:25:11.524415Z","caller":"traceutil/trace.go:171","msg":"trace[1078370841] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:515; }","duration":"825.985007ms","start":"2026-02-11T02:25:10.698411Z","end":"2026-02-11T02:25:11.524396Z","steps":["trace[1078370841] 'read index received' (duration: 100.513228ms)","trace[1078370841] 'applied index is now lower than readState.Index' (duration: 725.470989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:11.524503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.073549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:11.524523Z","caller":"traceutil/trace.go:171","msg":"trace[1418321295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"826.136991ms","start":"2026-02-11T02:25:10.69838Z","end":"2026-02-11T02:25:11.524517Z","steps":["trace[1418321295] 'agreement among raft nodes before linearized reading' (duration: 826.08448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:11.524567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:10.698365Z","time spent":"826.195352ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T02:25:11.52477Z","caller":"traceutil/trace.go:171","msg":"trace[1347949743] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.202446356s","start":"2026-02-11T02:25:10.322317Z","end":"2026-02-11T02:25:11.524764Z","steps":["trace[1347949743] 'process raft request' (duration: 1.201921563s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:11.524826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:10.322297Z","time spent":"1.202489876s","remote":"127.0.0.1:45642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:25:12.487011Z","caller":"traceutil/trace.go:171","msg":"trace[1779819245] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"154.729381ms","start":"2026-02-11T02:25:12.332265Z","end":"2026-02-11T02:25:12.486994Z","steps":["trace[1779819245] 'process raft request' (duration: 153.86208ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:13.905043Z","caller":"traceutil/trace.go:171","msg":"trace[892078000] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"106.431286ms","start":"2026-02-11T02:25:13.798592Z","end":"2026-02-11T02:25:13.905024Z","steps":["trace[892078000] 'process raft request' (duration: 99.542547ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:13.905368Z","caller":"traceutil/trace.go:171","msg":"trace[1397712888] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"105.060934ms","start":"2026-02-11T02:25:13.800298Z","end":"2026-02-11T02:25:13.905359Z","steps":["trace[1397712888] 'process raft request' (duration: 104.493791ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:13.905686Z","caller":"traceutil/trace.go:171","msg":"trace[1146038026] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:525; }","duration":"104.327326ms","start":"2026-02-11T02:25:13.801Z","end":"2026-02-11T02:25:13.905328Z","steps":["trace[1146038026] 'read index received' (duration: 97.17142ms)","trace[1146038026] 'applied index is now lower than readState.Index' (duration: 7.155216ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:13.905855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.819068ms","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-11T02:25:13.905881Z","caller":"traceutil/trace.go:171","msg":"trace[671417019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:506; }","duration":"104.892179ms","start":"2026-02-11T02:25:13.800982Z","end":"2026-02-11T02:25:13.905874Z","steps":["trace[671417019] 'agreement among raft nodes before linearized reading' (duration: 104.723685ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:14.042168Z","caller":"traceutil/trace.go:171","msg":"trace[1081765329] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"131.872697ms","start":"2026-02-11T02:25:13.910277Z","end":"2026-02-11T02:25:14.04215Z","steps":["trace[1081765329] 'process raft request' (duration: 97.932738ms)","trace[1081765329] 'compare' (duration: 33.839996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:15.049712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.025151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:15.04977Z","caller":"traceutil/trace.go:171","msg":"trace[2029195271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"349.124623ms","start":"2026-02-11T02:25:14.700632Z","end":"2026-02-11T02:25:15.049756Z","steps":["trace[2029195271] 'range keys from in-memory index tree' (duration: 348.937399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:15.049799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:14.70061Z","time spent":"349.183604ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T02:25:17.654917Z","caller":"traceutil/trace.go:171","msg":"trace[479070803] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"112.428056ms","start":"2026-02-11T02:25:17.542169Z","end":"2026-02-11T02:25:17.654597Z","steps":["trace[479070803] 'process raft request' (duration: 112.1736ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:22.320795Z","caller":"traceutil/trace.go:171","msg":"trace[1927387178] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"333.765524ms","start":"2026-02-11T02:25:21.987014Z","end":"2026-02-11T02:25:22.320779Z","steps":["trace[1927387178] 'process raft request' (duration: 333.63111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:22.320897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:21.987001Z","time spent":"333.841845ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:25:22.442454Z","caller":"traceutil/trace.go:171","msg":"trace[1866866249] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"449.057477ms","start":"2026-02-11T02:25:21.993371Z","end":"2026-02-11T02:25:22.442429Z","steps":["trace[1866866249] 'process raft request' (duration: 404.931861ms)","trace[1866866249] 'compare' (duration: 43.740817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:22.442553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:21.993356Z","time spent":"449.141779ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:25:22.442775Z","caller":"traceutil/trace.go:171","msg":"trace[1936338473] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"427.896484ms","start":"2026-02-11T02:25:22.014874Z","end":"2026-02-11T02:25:22.44277Z","steps":["trace[1936338473] 'process raft request' (duration: 427.439413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:22.442822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:22.014735Z","time spent":"428.053548ms","remote":"127.0.0.1:46504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1874,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:25:22.443015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.447215ms","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-11T02:25:22.443044Z","caller":"traceutil/trace.go:171","msg":"trace[1602997048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"369.498746ms","start":"2026-02-11T02:25:22.073528Z","end":"2026-02-11T02:25:22.443026Z","steps":["trace[1602997048] 'agreement among raft nodes before linearized reading' (duration: 369.439095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:22.443059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:22.073512Z","time spent":"369.543397ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T02:25:22.442948Z","caller":"traceutil/trace.go:171","msg":"trace[959190159] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:635; }","duration":"368.776379ms","start":"2026-02-11T02:25:22.073569Z","end":"2026-02-11T02:25:22.442345Z","steps":["trace[959190159] 'read index received' (duration: 247.152539ms)","trace[959190159] 'applied index is now lower than readState.Index' (duration: 121.62334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:22.443158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.346063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1889"} {"level":"info","ts":"2026-02-11T02:25:22.44317Z","caller":"traceutil/trace.go:171","msg":"trace[392647859] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:612; }","duration":"173.389543ms","start":"2026-02-11T02:25:22.269776Z","end":"2026-02-11T02:25:22.443165Z","steps":["trace[392647859] 'agreement among raft nodes before linearized reading' (duration: 173.367943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:23.176659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.872799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:25:23.176747Z","caller":"traceutil/trace.go:171","msg":"trace[1212880575] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"715.910437ms","start":"2026-02-11T02:25:22.460821Z","end":"2026-02-11T02:25:23.176731Z","steps":["trace[1212880575] 'read index received' (duration: 59.460044ms)","trace[1212880575] 'applied index is now lower than readState.Index' (duration: 656.449013ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:23.177009Z","caller":"traceutil/trace.go:171","msg":"trace[17556019] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"726.269256ms","start":"2026-02-11T02:25:22.450715Z","end":"2026-02-11T02:25:23.176984Z","steps":["trace[17556019] 'process raft request' (duration: 554.006149ms)","trace[17556019] 'compare' (duration: 171.648413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:23.177068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:22.450697Z","time spent":"726.341678ms","remote":"127.0.0.1:46504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:25:23.177321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.513647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:23.17739Z","caller":"traceutil/trace.go:171","msg":"trace[1330772622] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:614; }","duration":"697.679941ms","start":"2026-02-11T02:25:22.479693Z","end":"2026-02-11T02:25:23.177373Z","steps":["trace[1330772622] 'agreement among raft nodes before linearized reading' (duration: 697.521147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:23.177432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:22.479678Z","time spent":"697.744172ms","remote":"127.0.0.1:46544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-02-11T02:25:23.177605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.779034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:25:23.177648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.871946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:23.17771Z","caller":"traceutil/trace.go:171","msg":"trace[556799030] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:614; }","duration":"697.944368ms","start":"2026-02-11T02:25:22.479757Z","end":"2026-02-11T02:25:23.177701Z","steps":["trace[556799030] 'agreement among raft nodes before linearized reading' (duration: 697.864436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:23.177723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.906529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2252"} {"level":"warn","ts":"2026-02-11T02:25:23.17774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:22.479752Z","time spent":"697.980539ms","remote":"127.0.0.1:46544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"info","ts":"2026-02-11T02:25:23.177748Z","caller":"traceutil/trace.go:171","msg":"trace[840163726] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:614; }","duration":"716.9498ms","start":"2026-02-11T02:25:22.460791Z","end":"2026-02-11T02:25:23.177741Z","steps":["trace[840163726] 'agreement among raft nodes before linearized reading' (duration: 716.908029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:23.177779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:22.460777Z","time spent":"716.995672ms","remote":"127.0.0.1:46504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":2275,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"info","ts":"2026-02-11T02:25:23.177642Z","caller":"traceutil/trace.go:171","msg":"trace[777451729] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:614; }","duration":"697.856296ms","start":"2026-02-11T02:25:22.479774Z","end":"2026-02-11T02:25:23.17763Z","steps":["trace[777451729] 'agreement among raft nodes before linearized reading' (duration: 697.795695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:23.177882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:22.479771Z","time spent":"698.100992ms","remote":"127.0.0.1:46544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-11T02:25:23.347961Z","caller":"traceutil/trace.go:171","msg":"trace[2123398927] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"154.306709ms","start":"2026-02-11T02:25:23.193627Z","end":"2026-02-11T02:25:23.347934Z","steps":["trace[2123398927] 'process raft request' (duration: 106.106728ms)","trace[2123398927] 'compare' (duration: 47.994806ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:25.765779Z","caller":"traceutil/trace.go:171","msg":"trace[1111676271] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"229.796447ms","start":"2026-02-11T02:25:25.535965Z","end":"2026-02-11T02:25:25.765761Z","steps":["trace[1111676271] 'process raft request' (duration: 229.319675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:26.408315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050629439392872,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T02:25:26.421384Z","caller":"traceutil/trace.go:171","msg":"trace[1099583898] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"639.331925ms","start":"2026-02-11T02:25:25.782033Z","end":"2026-02-11T02:25:26.421365Z","steps":["trace[1099583898] 'process raft request' (duration: 639.235323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:26.421468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:25.782022Z","time spent":"639.393317ms","remote":"127.0.0.1:45642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:25:26.620255Z","caller":"traceutil/trace.go:171","msg":"trace[1820128035] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"712.678774ms","start":"2026-02-11T02:25:25.90756Z","end":"2026-02-11T02:25:26.620238Z","steps":["trace[1820128035] 'read index received' (duration: 514.234402ms)","trace[1820128035] 'applied index is now lower than readState.Index' (duration: 198.441552ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:26.620526Z","caller":"traceutil/trace.go:171","msg":"trace[2042441604] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"767.964607ms","start":"2026-02-11T02:25:25.852554Z","end":"2026-02-11T02:25:26.620519Z","steps":["trace[2042441604] 'process raft request' (duration: 767.570428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:26.620607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:25.852539Z","time spent":"768.012308ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:25:26.620791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.229106ms","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-11T02:25:26.620812Z","caller":"traceutil/trace.go:171","msg":"trace[924066521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"713.276748ms","start":"2026-02-11T02:25:25.907529Z","end":"2026-02-11T02:25:26.620805Z","steps":["trace[924066521] 'agreement among raft nodes before linearized reading' (duration: 713.198206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:26.62083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:25.907517Z","time spent":"713.309388ms","remote":"127.0.0.1:45704","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-11T02:25:26.629278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.785958ms","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-11T02:25:26.629318Z","caller":"traceutil/trace.go:171","msg":"trace[499137319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:631; }","duration":"720.959282ms","start":"2026-02-11T02:25:25.90835Z","end":"2026-02-11T02:25:26.629309Z","steps":["trace[499137319] 'agreement among raft nodes before linearized reading' (duration: 720.847079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:26.629345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:25.90834Z","time spent":"720.999002ms","remote":"127.0.0.1:45704","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-11T02:25:26.629545Z","caller":"traceutil/trace.go:171","msg":"trace[1346579769] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"199.149458ms","start":"2026-02-11T02:25:26.430386Z","end":"2026-02-11T02:25:26.629536Z","steps":["trace[1346579769] 'process raft request' (duration: 198.667807ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:26.629739Z","caller":"traceutil/trace.go:171","msg":"trace[1624141603] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"193.189656ms","start":"2026-02-11T02:25:26.436543Z","end":"2026-02-11T02:25:26.629732Z","steps":["trace[1624141603] 'process raft request' (duration: 192.580281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:26.629947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.626509ms","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-11T02:25:26.629968Z","caller":"traceutil/trace.go:171","msg":"trace[325058347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"161.673841ms","start":"2026-02-11T02:25:26.468287Z","end":"2026-02-11T02:25:26.629961Z","steps":["trace[325058347] 'agreement among raft nodes before linearized reading' (duration: 161.624589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:26.630067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.70081ms","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-11T02:25:26.630087Z","caller":"traceutil/trace.go:171","msg":"trace[945755090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"300.767522ms","start":"2026-02-11T02:25:26.329315Z","end":"2026-02-11T02:25:26.630082Z","steps":["trace[945755090] 'agreement among raft nodes before linearized reading' (duration: 300.71759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:26.630105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:26.329298Z","time spent":"300.802763ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T02:25:27.532309Z","caller":"traceutil/trace.go:171","msg":"trace[1078102754] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"105.852205ms","start":"2026-02-11T02:25:27.426442Z","end":"2026-02-11T02:25:27.532294Z","steps":["trace[1078102754] 'process raft request' (duration: 105.766103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:35.224715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050629439392979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:25:35.866942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.217761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:25:35.867117Z","caller":"traceutil/trace.go:171","msg":"trace[665133261] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:720; }","duration":"1.142764542s","start":"2026-02-11T02:25:34.724337Z","end":"2026-02-11T02:25:35.867101Z","steps":["trace[665133261] 'read index received' (duration: 905.325756ms)","trace[665133261] 'applied index is now lower than readState.Index' (duration: 237.437956ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:35.867301Z","caller":"traceutil/trace.go:171","msg":"trace[2111063101] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"1.148682132s","start":"2026-02-11T02:25:34.718607Z","end":"2026-02-11T02:25:35.867289Z","steps":["trace[2111063101] 'process raft request' (duration: 911.047702ms)","trace[2111063101] 'compare' (duration: 236.956015ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:35.867358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:34.718595Z","time spent":"1.148733044s","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:25:35.867521Z","caller":"traceutil/trace.go:171","msg":"trace[962682828] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"1.145127218s","start":"2026-02-11T02:25:34.722384Z","end":"2026-02-11T02:25:35.867511Z","steps":["trace[962682828] 'process raft request' (duration: 1.144641667s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:35.867575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:34.722375Z","time spent":"1.14517405s","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:25:35.86769Z","caller":"traceutil/trace.go:171","msg":"trace[1012527163] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"1.14393831s","start":"2026-02-11T02:25:34.723742Z","end":"2026-02-11T02:25:35.867681Z","steps":["trace[1012527163] 'process raft request' (duration: 1.143331156s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:35.867739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:34.723736Z","time spent":"1.143979871s","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:25:35.867908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14356661s","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-11T02:25:35.867942Z","caller":"traceutil/trace.go:171","msg":"trace[1300316556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:695; }","duration":"1.143609391s","start":"2026-02-11T02:25:34.724323Z","end":"2026-02-11T02:25:35.867932Z","steps":["trace[1300316556] 'agreement among raft nodes before linearized reading' (duration: 1.143508759s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:35.867973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:34.724318Z","time spent":"1.143648363s","remote":"127.0.0.1:45704","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-11T02:25:35.868124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.859301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T02:25:35.868155Z","caller":"traceutil/trace.go:171","msg":"trace[121030053] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:695; }","duration":"634.924202ms","start":"2026-02-11T02:25:35.233223Z","end":"2026-02-11T02:25:35.868147Z","steps":["trace[121030053] 'agreement among raft nodes before linearized reading' (duration: 634.84672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:35.868207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:35.233205Z","time spent":"634.994753ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-02-11T02:25:35.868464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.094189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:35.868498Z","caller":"traceutil/trace.go:171","msg":"trace[283438403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"174.153281ms","start":"2026-02-11T02:25:35.694337Z","end":"2026-02-11T02:25:35.86849Z","steps":["trace[283438403] 'agreement among raft nodes before linearized reading' (duration: 174.105049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:35.868629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.475008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:35.868659Z","caller":"traceutil/trace.go:171","msg":"trace[1812247027] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:695; }","duration":"212.539159ms","start":"2026-02-11T02:25:35.656113Z","end":"2026-02-11T02:25:35.868652Z","steps":["trace[1812247027] 'agreement among raft nodes before linearized reading' (duration: 212.493058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:36.373564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050629439392984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:25:36.526504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:35.872167Z","time spent":"654.334176ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T02:25:36.55656Z","caller":"traceutil/trace.go:171","msg":"trace[498500236] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"683.863933ms","start":"2026-02-11T02:25:35.872677Z","end":"2026-02-11T02:25:36.55654Z","steps":["trace[498500236] 'read index received' (duration: 654.087881ms)","trace[498500236] 'applied index is now lower than readState.Index' (duration: 29.775292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:36.556699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.001606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:36.556731Z","caller":"traceutil/trace.go:171","msg":"trace[1765852705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"684.056318ms","start":"2026-02-11T02:25:35.872665Z","end":"2026-02-11T02:25:36.556722Z","steps":["trace[1765852705] 'agreement among raft nodes before linearized reading' (duration: 683.958325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:36.556765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:35.872659Z","time spent":"684.097798ms","remote":"127.0.0.1:45460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T02:25:36.557126Z","caller":"traceutil/trace.go:171","msg":"trace[920708488] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"682.740667ms","start":"2026-02-11T02:25:35.87437Z","end":"2026-02-11T02:25:36.55711Z","steps":["trace[920708488] 'process raft request' (duration: 682.02593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:36.557223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:35.874361Z","time spent":"682.803768ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:25:43.035361Z","caller":"traceutil/trace.go:171","msg":"trace[138674807] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"339.158371ms","start":"2026-02-11T02:25:42.696188Z","end":"2026-02-11T02:25:43.035347Z","steps":["trace[138674807] 'read index received' (duration: 339.022158ms)","trace[138674807] 'applied index is now lower than readState.Index' (duration: 135.633µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:43.035532Z","caller":"traceutil/trace.go:171","msg":"trace[1918555334] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"439.855321ms","start":"2026-02-11T02:25:42.595667Z","end":"2026-02-11T02:25:43.035523Z","steps":["trace[1918555334] 'process raft request' (duration: 439.588045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:43.035602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:42.59565Z","time spent":"439.905853ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:25:43.035702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.51978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:43.035718Z","caller":"traceutil/trace.go:171","msg":"trace[18613471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"339.567371ms","start":"2026-02-11T02:25:42.696146Z","end":"2026-02-11T02:25:43.035713Z","steps":["trace[18613471] 'agreement among raft nodes before linearized reading' (duration: 339.54214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:43.035731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:42.696131Z","time spent":"339.596431ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T02:25:43.035846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.300622ms","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-11T02:25:43.035859Z","caller":"traceutil/trace.go:171","msg":"trace[1589963463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"270.313793ms","start":"2026-02-11T02:25:42.765542Z","end":"2026-02-11T02:25:43.035855Z","steps":["trace[1589963463] 'agreement among raft nodes before linearized reading' (duration: 270.256611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:43.411179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.328663ms","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-11T02:25:43.411296Z","caller":"traceutil/trace.go:171","msg":"trace[2127199307] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:721; }","duration":"123.467756ms","start":"2026-02-11T02:25:43.287811Z","end":"2026-02-11T02:25:43.411279Z","steps":["trace[2127199307] 'range keys from in-memory index tree' (duration: 123.310423ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:43.411926Z","caller":"traceutil/trace.go:171","msg":"trace[1400417813] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"371.604939ms","start":"2026-02-11T02:25:43.040309Z","end":"2026-02-11T02:25:43.411914Z","steps":["trace[1400417813] 'process raft request' (duration: 369.236961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:43.412015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:43.040301Z","time spent":"371.65752ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:25:54.176431Z","caller":"traceutil/trace.go:171","msg":"trace[1767101570] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"175.450981ms","start":"2026-02-11T02:25:54.000962Z","end":"2026-02-11T02:25:54.176413Z","steps":["trace[1767101570] 'read index received' (duration: 175.251267ms)","trace[1767101570] 'applied index is now lower than readState.Index' (duration: 198.724µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:54.176514Z","caller":"traceutil/trace.go:171","msg":"trace[1546985701] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"189.004825ms","start":"2026-02-11T02:25:53.987503Z","end":"2026-02-11T02:25:54.176508Z","steps":["trace[1546985701] 'process raft request' (duration: 188.776969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:54.176727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.749088ms","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-11T02:25:54.176751Z","caller":"traceutil/trace.go:171","msg":"trace[358449740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"175.80269ms","start":"2026-02-11T02:25:54.000941Z","end":"2026-02-11T02:25:54.176744Z","steps":["trace[358449740] 'agreement among raft nodes before linearized reading' (duration: 175.669346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:54.176955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.157734ms","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-11T02:25:54.177154Z","caller":"traceutil/trace.go:171","msg":"trace[1028656018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"175.363989ms","start":"2026-02-11T02:25:54.001763Z","end":"2026-02-11T02:25:54.177127Z","steps":["trace[1028656018] 'agreement among raft nodes before linearized reading' (duration: 175.051011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:54.703587Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050629439393155,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T02:25:54.78565Z","caller":"traceutil/trace.go:171","msg":"trace[1086945368] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"604.435402ms","start":"2026-02-11T02:25:54.181189Z","end":"2026-02-11T02:25:54.785625Z","steps":["trace[1086945368] 'process raft request' (duration: 603.810237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:54.785889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:54.181175Z","time spent":"604.614436ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:25:55.319304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.360886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:25:55.31937Z","caller":"traceutil/trace.go:171","msg":"trace[1870365040] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:783; }","duration":"1.116087092s","start":"2026-02-11T02:25:54.203271Z","end":"2026-02-11T02:25:55.319358Z","steps":["trace[1870365040] 'read index received' (duration: 581.736858ms)","trace[1870365040] 'applied index is now lower than readState.Index' (duration: 534.349654ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:55.319429Z","caller":"traceutil/trace.go:171","msg":"trace[1221636141] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"1.136683546s","start":"2026-02-11T02:25:54.182739Z","end":"2026-02-11T02:25:55.319423Z","steps":["trace[1221636141] 'process raft request' (duration: 860.136305ms)","trace[1221636141] 'compare' (duration: 276.195872ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:55.319461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:54.182731Z","time spent":"1.136713386s","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:25:55.319591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116316878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:55.319605Z","caller":"traceutil/trace.go:171","msg":"trace[1032704338] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:753; }","duration":"1.116361709s","start":"2026-02-11T02:25:54.203239Z","end":"2026-02-11T02:25:55.319601Z","steps":["trace[1032704338] 'agreement among raft nodes before linearized reading' (duration: 1.116330088s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:55.31962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:54.203227Z","time spent":"1.116389029s","remote":"127.0.0.1:45612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:25:55.319719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.087336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:25:55.319735Z","caller":"traceutil/trace.go:171","msg":"trace[169639057] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:753; }","duration":"668.136486ms","start":"2026-02-11T02:25:54.651594Z","end":"2026-02-11T02:25:55.319731Z","steps":["trace[169639057] 'agreement among raft nodes before linearized reading' (duration: 668.107916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:55.319752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:54.651573Z","time spent":"668.173758ms","remote":"127.0.0.1:45544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:25:55.319874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.36973ms","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-11T02:25:55.319889Z","caller":"traceutil/trace.go:171","msg":"trace[968932876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"208.430911ms","start":"2026-02-11T02:25:55.111451Z","end":"2026-02-11T02:25:55.319882Z","steps":["trace[968932876] 'agreement among raft nodes before linearized reading' (duration: 208.366299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:55.319981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.436209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:55.319993Z","caller":"traceutil/trace.go:171","msg":"trace[843899256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"621.484131ms","start":"2026-02-11T02:25:54.698505Z","end":"2026-02-11T02:25:55.319989Z","steps":["trace[843899256] 'agreement among raft nodes before linearized reading' (duration: 621.45951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:55.320005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:54.698486Z","time spent":"621.516341ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T02:25:55.832559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050629439393159,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:25:56.065314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.795247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:25:56.065557Z","caller":"traceutil/trace.go:171","msg":"trace[400025644] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"733.458261ms","start":"2026-02-11T02:25:55.332082Z","end":"2026-02-11T02:25:56.065541Z","steps":["trace[400025644] 'process raft request' (duration: 616.373017ms)","trace[400025644] 'compare' (duration: 116.475559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:56.065603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:55.33206Z","time spent":"733.521763ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:25:56.065738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:55.332536Z","time spent":"733.200696ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T02:25:56.065825Z","caller":"traceutil/trace.go:171","msg":"trace[989244191] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:785; }","duration":"733.327239ms","start":"2026-02-11T02:25:55.332491Z","end":"2026-02-11T02:25:56.065818Z","steps":["trace[989244191] 'read index received' (duration: 615.975208ms)","trace[989244191] 'applied index is now lower than readState.Index' (duration: 117.349761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:56.065933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.440563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:56.065953Z","caller":"traceutil/trace.go:171","msg":"trace[1754135193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"733.488932ms","start":"2026-02-11T02:25:55.332456Z","end":"2026-02-11T02:25:56.065945Z","steps":["trace[1754135193] 'agreement among raft nodes before linearized reading' (duration: 733.454631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:56.065971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:55.33244Z","time spent":"733.527302ms","remote":"127.0.0.1:45460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T02:25:56.066098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.86347ms","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-11T02:25:56.066114Z","caller":"traceutil/trace.go:171","msg":"trace[648892754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"550.87962ms","start":"2026-02-11T02:25:55.515229Z","end":"2026-02-11T02:25:56.066109Z","steps":["trace[648892754] 'agreement among raft nodes before linearized reading' (duration: 550.812148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:56.066132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:55.515168Z","time spent":"550.959922ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T02:26:00.327331Z","caller":"traceutil/trace.go:171","msg":"trace[2098363910] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"135.986665ms","start":"2026-02-11T02:26:00.191324Z","end":"2026-02-11T02:26:00.327311Z","steps":["trace[2098363910] 'read index received' (duration: 88.938639ms)","trace[2098363910] 'applied index is now lower than readState.Index' (duration: 47.047356ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:00.327599Z","caller":"traceutil/trace.go:171","msg":"trace[2142436990] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"165.682797ms","start":"2026-02-11T02:26:00.161901Z","end":"2026-02-11T02:26:00.327583Z","steps":["trace[2142436990] 'process raft request' (duration: 118.400835ms)","trace[2142436990] 'compare' (duration: 46.918693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:00.327945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.596671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:26:00.328287Z","caller":"traceutil/trace.go:171","msg":"trace[1518261591] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:768; }","duration":"136.972719ms","start":"2026-02-11T02:26:00.1913Z","end":"2026-02-11T02:26:00.328272Z","steps":["trace[1518261591] 'agreement among raft nodes before linearized reading' (duration: 136.557279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:00.328677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.410369ms","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-11T02:26:00.328969Z","caller":"traceutil/trace.go:171","msg":"trace[137019534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"119.719766ms","start":"2026-02-11T02:26:00.209235Z","end":"2026-02-11T02:26:00.328955Z","steps":["trace[137019534] 'agreement among raft nodes before linearized reading' (duration: 119.339937ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:05.509681Z","caller":"traceutil/trace.go:171","msg":"trace[1038842599] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"105.244709ms","start":"2026-02-11T02:26:05.404413Z","end":"2026-02-11T02:26:05.509657Z","steps":["trace[1038842599] 'process raft request' (duration: 105.099636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:10.523924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.949015ms","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-11T02:26:10.523996Z","caller":"traceutil/trace.go:171","msg":"trace[535772979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:848; }","duration":"116.027027ms","start":"2026-02-11T02:26:10.407953Z","end":"2026-02-11T02:26:10.52398Z","steps":["trace[535772979] 'range keys from in-memory index tree' (duration: 115.798732ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:13.72651Z","caller":"traceutil/trace.go:171","msg":"trace[638099822] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:893; }","duration":"184.737193ms","start":"2026-02-11T02:26:13.541757Z","end":"2026-02-11T02:26:13.726495Z","steps":["trace[638099822] 'read index received' (duration: 184.61011ms)","trace[638099822] 'applied index is now lower than readState.Index' (duration: 126.673µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:13.726651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.876446ms","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-11T02:26:13.726666Z","caller":"traceutil/trace.go:171","msg":"trace[486101928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"184.930538ms","start":"2026-02-11T02:26:13.541731Z","end":"2026-02-11T02:26:13.726661Z","steps":["trace[486101928] 'agreement among raft nodes before linearized reading' (duration: 184.823175ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:13.726872Z","caller":"traceutil/trace.go:171","msg":"trace[1664790469] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"791.725747ms","start":"2026-02-11T02:26:12.935141Z","end":"2026-02-11T02:26:13.726867Z","steps":["trace[1664790469] 'process raft request' (duration: 791.269846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:13.726919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:12.935132Z","time spent":"791.752927ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:14.041685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.855578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:14.041761Z","caller":"traceutil/trace.go:171","msg":"trace[207618588] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"137.937783ms","start":"2026-02-11T02:26:13.903812Z","end":"2026-02-11T02:26:14.04175Z","steps":["trace[207618588] 'read index received' (duration: 15.962562ms)","trace[207618588] 'applied index is now lower than readState.Index' (duration: 121.974331ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:14.041819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.009074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:14.041836Z","caller":"traceutil/trace.go:171","msg":"trace[118166933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:857; }","duration":"138.054976ms","start":"2026-02-11T02:26:13.903774Z","end":"2026-02-11T02:26:14.041829Z","steps":["trace[118166933] 'agreement among raft nodes before linearized reading' (duration: 138.007025ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:14.042041Z","caller":"traceutil/trace.go:171","msg":"trace[1932822769] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"311.147029ms","start":"2026-02-11T02:26:13.730857Z","end":"2026-02-11T02:26:14.042004Z","steps":["trace[1932822769] 'process raft request' (duration: 188.912533ms)","trace[1932822769] 'compare' (duration: 121.771316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:14.042082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:13.730838Z","time spent":"311.222221ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:14.569534Z","caller":"traceutil/trace.go:171","msg":"trace[1380743108] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"238.301696ms","start":"2026-02-11T02:26:14.331208Z","end":"2026-02-11T02:26:14.569509Z","steps":["trace[1380743108] 'process raft request' (duration: 238.185813ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:17.936642Z","caller":"traceutil/trace.go:171","msg":"trace[571036987] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"241.999834ms","start":"2026-02-11T02:26:17.694626Z","end":"2026-02-11T02:26:17.936625Z","steps":["trace[571036987] 'read index received' (duration: 241.904772ms)","trace[571036987] 'applied index is now lower than readState.Index' (duration: 94.712µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:17.936727Z","caller":"traceutil/trace.go:171","msg":"trace[73188176] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"464.527792ms","start":"2026-02-11T02:26:17.472194Z","end":"2026-02-11T02:26:17.936722Z","steps":["trace[73188176] 'process raft request' (duration: 464.333778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:17.936792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:17.47218Z","time spent":"464.562543ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:17.936864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.24216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:17.93688Z","caller":"traceutil/trace.go:171","msg":"trace[1360483658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:867; }","duration":"242.282121ms","start":"2026-02-11T02:26:17.694592Z","end":"2026-02-11T02:26:17.936875Z","steps":["trace[1360483658] 'agreement among raft nodes before linearized reading' (duration: 242.25705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:17.93704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.392792ms","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-11T02:26:17.937056Z","caller":"traceutil/trace.go:171","msg":"trace[1874351513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:867; }","duration":"201.430912ms","start":"2026-02-11T02:26:17.735621Z","end":"2026-02-11T02:26:17.937052Z","steps":["trace[1874351513] 'agreement among raft nodes before linearized reading' (duration: 201.369841ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:20.807925Z","caller":"traceutil/trace.go:171","msg":"trace[1761766407] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"152.743548ms","start":"2026-02-11T02:26:20.655166Z","end":"2026-02-11T02:26:20.807909Z","steps":["trace[1761766407] 'process raft request' (duration: 152.622125ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:20.808228Z","caller":"traceutil/trace.go:171","msg":"trace[2141449773] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:915; }","duration":"147.907023ms","start":"2026-02-11T02:26:20.660314Z","end":"2026-02-11T02:26:20.808221Z","steps":["trace[2141449773] 'read index received' (duration: 147.905052ms)","trace[2141449773] 'applied index is now lower than readState.Index' (duration: 1.651µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:20.80832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.992635ms","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-11T02:26:20.808335Z","caller":"traceutil/trace.go:171","msg":"trace[2019552575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:876; }","duration":"148.028305ms","start":"2026-02-11T02:26:20.660302Z","end":"2026-02-11T02:26:20.808331Z","steps":["trace[2019552575] 'agreement among raft nodes before linearized reading' (duration: 147.956933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:21.830942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.065991ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T02:26:21.831078Z","caller":"traceutil/trace.go:171","msg":"trace[869703138] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"1.02282145s","start":"2026-02-11T02:26:20.808245Z","end":"2026-02-11T02:26:21.831067Z","steps":["trace[869703138] 'read index received' (duration: 61.626016ms)","trace[869703138] 'applied index is now lower than readState.Index' (duration: 961.194814ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:21.831265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13763312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:21.831282Z","caller":"traceutil/trace.go:171","msg":"trace[265444066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"1.13768366s","start":"2026-02-11T02:26:20.693592Z","end":"2026-02-11T02:26:21.831275Z","steps":["trace[265444066] 'agreement among raft nodes before linearized reading' (duration: 1.137635709s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:21.831299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:20.69358Z","time spent":"1.13771479s","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T02:26:21.831283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134494634s","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-11T02:26:21.831417Z","caller":"traceutil/trace.go:171","msg":"trace[1744397847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:876; }","duration":"1.134667628s","start":"2026-02-11T02:26:20.696744Z","end":"2026-02-11T02:26:21.831412Z","steps":["trace[1744397847] 'agreement among raft nodes before linearized reading' (duration: 1.134437642s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:21.831433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:20.696728Z","time spent":"1.134699629s","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T02:26:21.831622Z","caller":"traceutil/trace.go:171","msg":"trace[636931311] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"1.02073401s","start":"2026-02-11T02:26:20.810881Z","end":"2026-02-11T02:26:21.831615Z","steps":["trace[636931311] 'process raft request' (duration: 1.020128305s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:21.831669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:20.81087Z","time spent":"1.02076522s","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:22.492252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050629439393457,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:26:22.84916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.753576017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:26:22.849206Z","caller":"traceutil/trace.go:171","msg":"trace[2018570347] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:877; }","duration":"1.753650458s","start":"2026-02-11T02:26:21.095544Z","end":"2026-02-11T02:26:22.849195Z","steps":["trace[2018570347] 'agreement among raft nodes before linearized reading' (duration: 736.061354ms)","trace[2018570347] 'count revisions from in-memory index tree' (duration: 1.017522613s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:22.84923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.095529Z","time spent":"1.75369541s","remote":"127.0.0.1:45788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:26:22.84941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053903914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:26:22.849436Z","caller":"traceutil/trace.go:171","msg":"trace[1308490562] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:877; }","duration":"1.053953774s","start":"2026-02-11T02:26:21.795477Z","end":"2026-02-11T02:26:22.849431Z","steps":["trace[1308490562] 'agreement among raft nodes before linearized reading' (duration: 36.243267ms)","trace[1308490562] 'count revisions from in-memory index tree' (duration: 1.017679985s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:22.84945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.795463Z","time spent":"1.053983686s","remote":"127.0.0.1:45806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:26:22.849518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01614358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:22.84953Z","caller":"traceutil/trace.go:171","msg":"trace[1091496695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"1.01616893s","start":"2026-02-11T02:26:21.833358Z","end":"2026-02-11T02:26:22.849526Z","steps":["trace[1091496695] 'range keys from in-memory index tree' (duration: 1.016106129s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:22.849542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.833347Z","time spent":"1.016192001s","remote":"127.0.0.1:45460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T02:26:22.850231Z","caller":"traceutil/trace.go:171","msg":"trace[34733751] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"858.354241ms","start":"2026-02-11T02:26:21.99187Z","end":"2026-02-11T02:26:22.850225Z","steps":["trace[34733751] 'read index received' (duration: 840.153466ms)","trace[34733751] 'applied index is now lower than readState.Index' (duration: 18.200275ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:22.85033Z","caller":"traceutil/trace.go:171","msg":"trace[1338013888] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"1.015133586s","start":"2026-02-11T02:26:21.835191Z","end":"2026-02-11T02:26:22.850324Z","steps":["trace[1338013888] 'process raft request' (duration: 996.845669ms)","trace[1338013888] 'compare' (duration: 17.036217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:22.850373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.835181Z","time spent":"1.015161896s","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:22.850486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.615188ms","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-11T02:26:22.850498Z","caller":"traceutil/trace.go:171","msg":"trace[1751061636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"858.649098ms","start":"2026-02-11T02:26:21.991845Z","end":"2026-02-11T02:26:22.850494Z","steps":["trace[1751061636] 'agreement among raft nodes before linearized reading' (duration: 858.588277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:22.85051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.991831Z","time spent":"858.676499ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T02:26:22.850589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.816324ms","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-11T02:26:22.850601Z","caller":"traceutil/trace.go:171","msg":"trace[1181932481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"706.849024ms","start":"2026-02-11T02:26:22.143748Z","end":"2026-02-11T02:26:22.850597Z","steps":["trace[1181932481] 'agreement among raft nodes before linearized reading' (duration: 706.812773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:22.850611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:22.143735Z","time spent":"706.874005ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T02:26:23.988943Z","caller":"traceutil/trace.go:171","msg":"trace[1801518414] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"152.04334ms","start":"2026-02-11T02:26:23.836885Z","end":"2026-02-11T02:26:23.988928Z","steps":["trace[1801518414] 'process raft request' (duration: 151.700452ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:25.032758Z","caller":"traceutil/trace.go:171","msg":"trace[430348631] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"143.982698ms","start":"2026-02-11T02:26:24.88875Z","end":"2026-02-11T02:26:25.032732Z","steps":["trace[430348631] 'read index received' (duration: 143.796523ms)","trace[430348631] 'applied index is now lower than readState.Index' (duration: 185.565µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:25.033324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.875195ms","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-11T02:26:25.033365Z","caller":"traceutil/trace.go:171","msg":"trace[393155092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"128.961768ms","start":"2026-02-11T02:26:24.904392Z","end":"2026-02-11T02:26:25.033354Z","steps":["trace[393155092] 'agreement among raft nodes before linearized reading' (duration: 128.781884ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:25.033495Z","caller":"traceutil/trace.go:171","msg":"trace[907705652] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"175.112932ms","start":"2026-02-11T02:26:24.85837Z","end":"2026-02-11T02:26:25.033483Z","steps":["trace[907705652] 'process raft request' (duration: 174.244182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:25.033636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.884199ms","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-11T02:26:25.033667Z","caller":"traceutil/trace.go:171","msg":"trace[730840809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"144.94236ms","start":"2026-02-11T02:26:24.888716Z","end":"2026-02-11T02:26:25.033659Z","steps":["trace[730840809] 'agreement among raft nodes before linearized reading' (duration: 144.857988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:25.033801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.298532ms","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-11T02:26:25.033826Z","caller":"traceutil/trace.go:171","msg":"trace[364311810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"128.335932ms","start":"2026-02-11T02:26:24.905482Z","end":"2026-02-11T02:26:25.033818Z","steps":["trace[364311810] 'agreement among raft nodes before linearized reading' (duration: 128.26222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:25.423114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.353572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:25.423339Z","caller":"traceutil/trace.go:171","msg":"trace[106295530] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:923; }","duration":"183.597305ms","start":"2026-02-11T02:26:25.239727Z","end":"2026-02-11T02:26:25.423325Z","steps":["trace[106295530] 'read index received' (duration: 47.911µs)","trace[106295530] 'applied index is now lower than readState.Index' (duration: 183.548614ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:25.423472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.757509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T02:26:25.423502Z","caller":"traceutil/trace.go:171","msg":"trace[679836271] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:886; }","duration":"183.827191ms","start":"2026-02-11T02:26:25.239666Z","end":"2026-02-11T02:26:25.423493Z","steps":["trace[679836271] 'agreement among raft nodes before linearized reading' (duration: 183.706618ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:25.423755Z","caller":"traceutil/trace.go:171","msg":"trace[1746547227] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"381.30306ms","start":"2026-02-11T02:26:25.042441Z","end":"2026-02-11T02:26:25.423744Z","steps":["trace[1746547227] 'process raft request' (duration: 108.257552ms)","trace[1746547227] 'compare' (duration: 272.133436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:25.423816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:25.042427Z","time spent":"381.358101ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:25.423928Z","caller":"traceutil/trace.go:171","msg":"trace[966455777] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"381.407902ms","start":"2026-02-11T02:26:25.042512Z","end":"2026-02-11T02:26:25.42392Z","steps":["trace[966455777] 'process raft request' (duration: 380.699475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:25.423972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:25.042507Z","time spent":"381.440763ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:25.42407Z","caller":"traceutil/trace.go:171","msg":"trace[1405853917] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"381.525125ms","start":"2026-02-11T02:26:25.042537Z","end":"2026-02-11T02:26:25.424062Z","steps":["trace[1405853917] 'process raft request' (duration: 380.718446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:25.424111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:25.042534Z","time spent":"381.554686ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:26.228413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.027624ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-11T02:26:26.228493Z","caller":"traceutil/trace.go:171","msg":"trace[908304187] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"531.842224ms","start":"2026-02-11T02:26:25.696639Z","end":"2026-02-11T02:26:26.228481Z","steps":["trace[908304187] 'read index received' (duration: 317.690307ms)","trace[908304187] 'applied index is now lower than readState.Index' (duration: 214.150927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:26.2285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:25.426522Z","time spent":"801.973351ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T02:26:26.228562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.931806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:26.228576Z","caller":"traceutil/trace.go:171","msg":"trace[790856155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"531.986877ms","start":"2026-02-11T02:26:25.696584Z","end":"2026-02-11T02:26:26.228571Z","steps":["trace[790856155] 'agreement among raft nodes before linearized reading' (duration: 531.951586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.228593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:25.696564Z","time spent":"532.025159ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T02:26:26.228758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.208955ms","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-11T02:26:26.228771Z","caller":"traceutil/trace.go:171","msg":"trace[1529854063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"236.247146ms","start":"2026-02-11T02:26:25.99252Z","end":"2026-02-11T02:26:26.228767Z","steps":["trace[1529854063] 'agreement among raft nodes before linearized reading' (duration: 236.177524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.476174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.517141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:26.476269Z","caller":"traceutil/trace.go:171","msg":"trace[1812941133] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"212.890307ms","start":"2026-02-11T02:26:26.263364Z","end":"2026-02-11T02:26:26.476255Z","steps":["trace[1812941133] 'read index received' (duration: 93.150671ms)","trace[1812941133] 'applied index is now lower than readState.Index' (duration: 119.738336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:26.476363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.00042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-11T02:26:26.476389Z","caller":"traceutil/trace.go:171","msg":"trace[26682738] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:888; }","duration":"213.047541ms","start":"2026-02-11T02:26:26.263332Z","end":"2026-02-11T02:26:26.476379Z","steps":["trace[26682738] 'agreement among raft nodes before linearized reading' (duration: 212.963459ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:26.476692Z","caller":"traceutil/trace.go:171","msg":"trace[70497773] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"237.291162ms","start":"2026-02-11T02:26:26.239389Z","end":"2026-02-11T02:26:26.47668Z","steps":["trace[70497773] 'process raft request' (duration: 117.174756ms)","trace[70497773] 'compare' (duration: 119.239804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:27.780172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.162166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:27.780385Z","caller":"traceutil/trace.go:171","msg":"trace[2034326150] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"343.614307ms","start":"2026-02-11T02:26:27.436759Z","end":"2026-02-11T02:26:27.780373Z","steps":["trace[2034326150] 'process raft request' (duration: 127.204795ms)","trace[2034326150] 'compare' (duration: 215.93037ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:27.78042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:27.436749Z","time spent":"343.655168ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:27.780506Z","caller":"traceutil/trace.go:171","msg":"trace[526748636] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"341.305911ms","start":"2026-02-11T02:26:27.439196Z","end":"2026-02-11T02:26:27.780502Z","steps":["trace[526748636] 'process raft request' (duration: 341.038715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:27.780528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:27.439188Z","time spent":"341.328453ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:28.487214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.526452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:28.487294Z","caller":"traceutil/trace.go:171","msg":"trace[801983558] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"494.684395ms","start":"2026-02-11T02:26:27.9926Z","end":"2026-02-11T02:26:28.487285Z","steps":["trace[801983558] 'process raft request' (duration: 101.03645ms)","trace[801983558] 'compare' (duration: 393.337368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:28.487323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:27.992581Z","time spent":"494.728296ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:29.197154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050629439393506,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T02:26:29.505255Z","caller":"traceutil/trace.go:171","msg":"trace[1306217045] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"808.430766ms","start":"2026-02-11T02:26:28.696788Z","end":"2026-02-11T02:26:29.505219Z","steps":["trace[1306217045] 'read index received' (duration: 722.436717ms)","trace[1306217045] 'applied index is now lower than readState.Index' (duration: 85.993179ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:29.507108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.360741ms","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-11T02:26:29.507157Z","caller":"traceutil/trace.go:171","msg":"trace[889685691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:894; }","duration":"442.450764ms","start":"2026-02-11T02:26:29.064694Z","end":"2026-02-11T02:26:29.507144Z","steps":["trace[889685691] 'agreement among raft nodes before linearized reading' (duration: 442.30024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.507195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.064674Z","time spent":"442.511346ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:26:29.507366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.575887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:29.507394Z","caller":"traceutil/trace.go:171","msg":"trace[2130973117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"810.633869ms","start":"2026-02-11T02:26:28.696751Z","end":"2026-02-11T02:26:29.507385Z","steps":["trace[2130973117] 'agreement among raft nodes before linearized reading' (duration: 810.591358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.507419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:28.696735Z","time spent":"810.679279ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T02:26:29.505455Z","caller":"traceutil/trace.go:171","msg":"trace[765948670] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"1.015006723s","start":"2026-02-11T02:26:28.490436Z","end":"2026-02-11T02:26:29.505443Z","steps":["trace[765948670] 'process raft request' (duration: 928.791249ms)","trace[765948670] 'compare' (duration: 85.808105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:29.512459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:28.490425Z","time spent":"1.02199068s","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:29.911553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.94217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:29.911744Z","caller":"traceutil/trace.go:171","msg":"trace[1387200271] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"396.440152ms","start":"2026-02-11T02:26:29.515288Z","end":"2026-02-11T02:26:29.911728Z","steps":["trace[1387200271] 'process raft request' (duration: 396.36332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.911806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.515283Z","time spent":"396.494353ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:29.912004Z","caller":"traceutil/trace.go:171","msg":"trace[1958364978] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"396.74261ms","start":"2026-02-11T02:26:29.51525Z","end":"2026-02-11T02:26:29.911993Z","steps":["trace[1958364978] 'read index received' (duration: 175.360809ms)","trace[1958364978] 'applied index is now lower than readState.Index' (duration: 221.378241ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:29.912027Z","caller":"traceutil/trace.go:171","msg":"trace[717615389] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"399.086896ms","start":"2026-02-11T02:26:29.512882Z","end":"2026-02-11T02:26:29.911969Z","steps":["trace[717615389] 'process raft request' (duration: 177.648964ms)","trace[717615389] 'compare' (duration: 220.806427ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:29.912154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.897623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:26:29.912158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.512871Z","time spent":"399.216149ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:29.912182Z","caller":"traceutil/trace.go:171","msg":"trace[134215527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"396.938805ms","start":"2026-02-11T02:26:29.515234Z","end":"2026-02-11T02:26:29.912173Z","steps":["trace[134215527] 'agreement among raft nodes before linearized reading' (duration: 396.866473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.912211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.515227Z","time spent":"396.977656ms","remote":"127.0.0.1:45460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T02:26:29.912359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.389578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:29.912385Z","caller":"traceutil/trace.go:171","msg":"trace[1506122303] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:896; }","duration":"352.438559ms","start":"2026-02-11T02:26:29.559939Z","end":"2026-02-11T02:26:29.912377Z","steps":["trace[1506122303] 'agreement among raft nodes before linearized reading' (duration: 352.396408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.912413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.55993Z","time spent":"352.474601ms","remote":"127.0.0.1:45734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:26:29.912618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.426191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-11T02:26:29.912634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.624851ms","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-11T02:26:29.912676Z","caller":"traceutil/trace.go:171","msg":"trace[1210185162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"126.681892ms","start":"2026-02-11T02:26:29.78598Z","end":"2026-02-11T02:26:29.912662Z","steps":["trace[1210185162] 'agreement among raft nodes before linearized reading' (duration: 126.541149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.912684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.059383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:26:29.912711Z","caller":"traceutil/trace.go:171","msg":"trace[1698945586] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:896; }","duration":"315.110704ms","start":"2026-02-11T02:26:29.597594Z","end":"2026-02-11T02:26:29.912704Z","steps":["trace[1698945586] 'agreement among raft nodes before linearized reading' (duration: 315.066223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.912737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.597581Z","time spent":"315.149076ms","remote":"127.0.0.1:45880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:26:29.912874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.211992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:26:29.912903Z","caller":"traceutil/trace.go:171","msg":"trace[572800444] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:896; }","duration":"329.264404ms","start":"2026-02-11T02:26:29.583631Z","end":"2026-02-11T02:26:29.912895Z","steps":["trace[572800444] 'agreement among raft nodes before linearized reading' (duration: 329.218333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.912931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.583618Z","time spent":"329.305744ms","remote":"127.0.0.1:45696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-11T02:26:29.912643Z","caller":"traceutil/trace.go:171","msg":"trace[615039475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"127.484561ms","start":"2026-02-11T02:26:29.785151Z","end":"2026-02-11T02:26:29.912636Z","steps":["trace[615039475] 'agreement among raft nodes before linearized reading' (duration: 127.376129ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:30.163649Z","caller":"traceutil/trace.go:171","msg":"trace[190261920] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"243.032409ms","start":"2026-02-11T02:26:29.920594Z","end":"2026-02-11T02:26:30.163626Z","steps":["trace[190261920] 'process raft request' (duration: 176.26433ms)","trace[190261920] 'compare' (duration: 66.548693ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:30.305081Z","caller":"traceutil/trace.go:171","msg":"trace[1668568154] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:937; }","duration":"382.198531ms","start":"2026-02-11T02:26:29.922868Z","end":"2026-02-11T02:26:30.305067Z","steps":["trace[1668568154] 'read index received' (duration: 173.999626ms)","trace[1668568154] 'applied index is now lower than readState.Index' (duration: 208.198155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:30.305161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.278263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:30.305182Z","caller":"traceutil/trace.go:171","msg":"trace[72230055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"382.322453ms","start":"2026-02-11T02:26:29.922853Z","end":"2026-02-11T02:26:30.305176Z","steps":["trace[72230055] 'agreement among raft nodes before linearized reading' (duration: 382.265862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:30.305203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.922847Z","time spent":"382.350724ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T02:26:30.305385Z","caller":"traceutil/trace.go:171","msg":"trace[641107474] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"382.630851ms","start":"2026-02-11T02:26:29.922747Z","end":"2026-02-11T02:26:30.305378Z","steps":["trace[641107474] 'process raft request' (duration: 381.910474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:30.305439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.922739Z","time spent":"382.662172ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:30.305567Z","caller":"traceutil/trace.go:171","msg":"trace[568454927] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"277.849793ms","start":"2026-02-11T02:26:30.027692Z","end":"2026-02-11T02:26:30.305542Z","steps":["trace[568454927] 'process raft request' (duration: 277.345231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:32.051415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.60761ms","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-11T02:26:32.05147Z","caller":"traceutil/trace.go:171","msg":"trace[348890295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:902; }","duration":"131.704242ms","start":"2026-02-11T02:26:31.919754Z","end":"2026-02-11T02:26:32.051458Z","steps":["trace[348890295] 'range keys from in-memory index tree' (duration: 131.484769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:32.051658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.04054ms","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-11T02:26:32.051673Z","caller":"traceutil/trace.go:171","msg":"trace[894876410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"132.084992ms","start":"2026-02-11T02:26:31.919583Z","end":"2026-02-11T02:26:32.051668Z","steps":["trace[894876410] 'range keys from in-memory index tree' (duration: 131.977159ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:32.404819Z","caller":"traceutil/trace.go:171","msg":"trace[62062170] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"329.527089ms","start":"2026-02-11T02:26:32.075268Z","end":"2026-02-11T02:26:32.404795Z","steps":["trace[62062170] 'process raft request' (duration: 329.357105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:32.404989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:32.075252Z","time spent":"329.655202ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T02:26:33.026351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.794448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:33.026522Z","caller":"traceutil/trace.go:171","msg":"trace[1644113347] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"615.345062ms","start":"2026-02-11T02:26:32.411166Z","end":"2026-02-11T02:26:33.026511Z","steps":["trace[1644113347] 'process raft request' (duration: 615.310051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:33.026571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:32.411118Z","time spent":"615.436104ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T02:26:33.026885Z","caller":"traceutil/trace.go:171","msg":"trace[1736528709] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"951.432966ms","start":"2026-02-11T02:26:32.075441Z","end":"2026-02-11T02:26:33.026873Z","steps":["trace[1736528709] 'process raft request' (duration: 513.058023ms)","trace[1736528709] 'compare' (duration: 437.338947ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:33.026934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:32.075435Z","time spent":"951.477227ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:33.027029Z","caller":"traceutil/trace.go:171","msg":"trace[1385052887] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"951.545809ms","start":"2026-02-11T02:26:32.075477Z","end":"2026-02-11T02:26:33.027022Z","steps":["trace[1385052887] 'process raft request' (duration: 950.940383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:33.027064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:32.075474Z","time spent":"951.569809ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:33.027141Z","caller":"traceutil/trace.go:171","msg":"trace[879075740] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:944; }","duration":"859.303575ms","start":"2026-02-11T02:26:32.167831Z","end":"2026-02-11T02:26:33.027135Z","steps":["trace[879075740] 'read index received' (duration: 236.871144ms)","trace[879075740] 'applied index is now lower than readState.Index' (duration: 622.431421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:33.027337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.50382ms","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-11T02:26:33.027356Z","caller":"traceutil/trace.go:171","msg":"trace[972465129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"859.552621ms","start":"2026-02-11T02:26:32.167797Z","end":"2026-02-11T02:26:33.02735Z","steps":["trace[972465129] 'agreement among raft nodes before linearized reading' (duration: 859.476199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:33.027374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:32.167782Z","time spent":"859.587952ms","remote":"127.0.0.1:45704","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-11T02:26:33.027464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.520821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:33.027484Z","caller":"traceutil/trace.go:171","msg":"trace[1574391086] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:906; }","duration":"812.571903ms","start":"2026-02-11T02:26:32.214907Z","end":"2026-02-11T02:26:33.027479Z","steps":["trace[1574391086] 'agreement among raft nodes before linearized reading' (duration: 812.541092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:33.027501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:32.214893Z","time spent":"812.604264ms","remote":"127.0.0.1:45792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"warn","ts":"2026-02-11T02:26:33.027653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.077704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:33.027672Z","caller":"traceutil/trace.go:171","msg":"trace[1421728697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"332.120355ms","start":"2026-02-11T02:26:32.695546Z","end":"2026-02-11T02:26:33.027666Z","steps":["trace[1421728697] 'agreement among raft nodes before linearized reading' (duration: 332.091785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:33.02769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:32.695521Z","time spent":"332.166186ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T02:26:33.027831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.418719ms","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-11T02:26:33.027851Z","caller":"traceutil/trace.go:171","msg":"trace[1251243427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"714.481821ms","start":"2026-02-11T02:26:32.313362Z","end":"2026-02-11T02:26:33.027844Z","steps":["trace[1251243427] 'agreement among raft nodes before linearized reading' (duration: 714.401139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:33.02787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:32.31334Z","time spent":"714.525292ms","remote":"127.0.0.1:45704","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-11T02:26:33.427503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.702865ms","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-11T02:26:33.427577Z","caller":"traceutil/trace.go:171","msg":"trace[820526787] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:908; }","duration":"135.785297ms","start":"2026-02-11T02:26:33.291781Z","end":"2026-02-11T02:26:33.427567Z","steps":["trace[820526787] 'range keys from in-memory index tree' (duration: 135.691565ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:40.523264Z","caller":"traceutil/trace.go:171","msg":"trace[295261928] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"113.787808ms","start":"2026-02-11T02:26:40.409449Z","end":"2026-02-11T02:26:40.523237Z","steps":["trace[295261928] 'process raft request' (duration: 75.730106ms)","trace[295261928] 'compare' (duration: 37.886548ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:43.295849Z","caller":"traceutil/trace.go:171","msg":"trace[92010372] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"117.792291ms","start":"2026-02-11T02:26:43.178035Z","end":"2026-02-11T02:26:43.295827Z","steps":["trace[92010372] 'process raft request' (duration: 53.958556ms)","trace[92010372] 'compare' (duration: 63.64444ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:47.920529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.703369738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:47.9206Z","caller":"traceutil/trace.go:171","msg":"trace[1267319713] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:985; }","duration":"1.703469539s","start":"2026-02-11T02:26:46.217111Z","end":"2026-02-11T02:26:47.92058Z","steps":["trace[1267319713] 'range keys from in-memory index tree' (duration: 1.703316327s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:47.920639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:46.217098Z","time spent":"1.703531512s","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-11T02:26:47.921425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281139343s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:47.921494Z","caller":"traceutil/trace.go:171","msg":"trace[2022124213] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"1.225382852s","start":"2026-02-11T02:26:46.696099Z","end":"2026-02-11T02:26:47.921482Z","steps":["trace[2022124213] 'read index received' (duration: 33.171µs)","trace[2022124213] 'applied index is now lower than readState.Index' (duration: 1.225348271s)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:47.921604Z","caller":"traceutil/trace.go:171","msg":"trace[1557429589] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.510583529s","start":"2026-02-11T02:26:46.411007Z","end":"2026-02-11T02:26:47.92159Z","steps":["trace[1557429589] 'process raft request' (duration: 229.21521ms)","trace[1557429589] 'compare' (duration: 1.280660861s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:47.921654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:46.410982Z","time spent":"1.510647131s","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T02:26:47.921875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225772151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:47.921908Z","caller":"traceutil/trace.go:171","msg":"trace[1260680864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"1.225830463s","start":"2026-02-11T02:26:46.696067Z","end":"2026-02-11T02:26:47.921897Z","steps":["trace[1260680864] 'agreement among raft nodes before linearized reading' (duration: 1.225752261s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:47.921943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:46.696051Z","time spent":"1.225885824s","remote":"127.0.0.1:45460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T02:26:47.922202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.566108ms","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-11T02:26:47.922237Z","caller":"traceutil/trace.go:171","msg":"trace[194181191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"781.63351ms","start":"2026-02-11T02:26:47.140593Z","end":"2026-02-11T02:26:47.922227Z","steps":["trace[194181191] 'agreement among raft nodes before linearized reading' (duration: 781.505806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:47.922268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:47.140575Z","time spent":"781.685771ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:26:47.922597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.696295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-11T02:26:47.92263Z","caller":"traceutil/trace.go:171","msg":"trace[106838631] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:986; }","duration":"252.787558ms","start":"2026-02-11T02:26:47.669832Z","end":"2026-02-11T02:26:47.922619Z","steps":["trace[106838631] 'agreement among raft nodes before linearized reading' (duration: 252.694875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:47.923041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.123205ms","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-11T02:26:47.923099Z","caller":"traceutil/trace.go:171","msg":"trace[156103687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"741.179486ms","start":"2026-02-11T02:26:47.18189Z","end":"2026-02-11T02:26:47.923069Z","steps":["trace[156103687] 'agreement among raft nodes before linearized reading' (duration: 741.063553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:47.923132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:47.181883Z","time spent":"741.241628ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T02:26:47.923331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.77542ms","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-11T02:26:47.923364Z","caller":"traceutil/trace.go:171","msg":"trace[1724597461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:986; }","duration":"767.833272ms","start":"2026-02-11T02:26:47.155521Z","end":"2026-02-11T02:26:47.923354Z","steps":["trace[1724597461] 'agreement among raft nodes before linearized reading' (duration: 767.746539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:47.923396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:47.155509Z","time spent":"767.878962ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T02:26:47.923583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.557894ms","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-11T02:26:47.923611Z","caller":"traceutil/trace.go:171","msg":"trace[651957247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"769.610516ms","start":"2026-02-11T02:26:47.153992Z","end":"2026-02-11T02:26:47.923602Z","steps":["trace[651957247] 'agreement among raft nodes before linearized reading' (duration: 769.532314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:47.923635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:47.153979Z","time spent":"769.650477ms","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T02:26:47.924531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.318598ms","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-11T02:26:47.92457Z","caller":"traceutil/trace.go:171","msg":"trace[230107847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"260.3948ms","start":"2026-02-11T02:26:47.664164Z","end":"2026-02-11T02:26:47.924559Z","steps":["trace[230107847] 'agreement among raft nodes before linearized reading' (duration: 258.581184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:12.295282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.302988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:27:12.295413Z","caller":"traceutil/trace.go:171","msg":"trace[325918836] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"114.667461ms","start":"2026-02-11T02:27:12.180734Z","end":"2026-02-11T02:27:12.295402Z","steps":["trace[325918836] 'read index received' (duration: 14.150798ms)","trace[325918836] 'applied index is now lower than readState.Index' (duration: 100.515783ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:12.29554Z","caller":"traceutil/trace.go:171","msg":"trace[962743229] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"115.559614ms","start":"2026-02-11T02:27:12.179974Z","end":"2026-02-11T02:27:12.295534Z","steps":["trace[962743229] 'process raft request' (duration: 14.951208ms)","trace[962743229] 'compare' (duration: 100.135804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:12.295688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.948969ms","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-11T02:27:12.295701Z","caller":"traceutil/trace.go:171","msg":"trace[1390626880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"114.97588ms","start":"2026-02-11T02:27:12.180721Z","end":"2026-02-11T02:27:12.295697Z","steps":["trace[1390626880] 'agreement among raft nodes before linearized reading' (duration: 114.907248ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:32.730644Z","caller":"traceutil/trace.go:171","msg":"trace[1952633034] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"106.204094ms","start":"2026-02-11T02:27:32.624417Z","end":"2026-02-11T02:27:32.730621Z","steps":["trace[1952633034] 'process raft request' (duration: 61.061565ms)","trace[1952633034] 'compare' (duration: 44.790831ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:34.412841Z","caller":"traceutil/trace.go:171","msg":"trace[1097139313] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"109.333079ms","start":"2026-02-11T02:27:34.303486Z","end":"2026-02-11T02:27:34.412819Z","steps":["trace[1097139313] 'process raft request' (duration: 109.048172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:28:35.799044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.519707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:28:35.799131Z","caller":"traceutil/trace.go:171","msg":"trace[1522689906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1445; }","duration":"101.65161ms","start":"2026-02-11T02:28:35.697462Z","end":"2026-02-11T02:28:35.799114Z","steps":["trace[1522689906] 'range keys from in-memory index tree' (duration: 101.439855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:30:03.739287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.085347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:30:03.739708Z","caller":"traceutil/trace.go:171","msg":"trace[851792693] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"175.139568ms","start":"2026-02-11T02:30:03.564529Z","end":"2026-02-11T02:30:03.739669Z","steps":["trace[851792693] 'process raft request' (duration: 56.713004ms)","trace[851792693] 'compare' (duration: 114.895492ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:30:03.742155Z","caller":"traceutil/trace.go:171","msg":"trace[475884589] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"176.696995ms","start":"2026-02-11T02:30:03.565435Z","end":"2026-02-11T02:30:03.742132Z","steps":["trace[475884589] 'process raft request' (duration: 174.990104ms)"],"step_count":1}