{"level":"warn","ts":"2026-02-18T02:51:04.698791Z","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-18T02:51:04.699042Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.224:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.224:2380","--initial-cluster=instance=https://199.19.213.224:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.224:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.224: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-18T02:51:04.699186Z","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-18T02:51:04.699321Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-02-18T02:51:04.699397Z","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-18T02:51:04.70352Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"]} {"level":"info","ts":"2026-02-18T02:51:04.703679Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.224: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-18T02:51:04.71051Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.171082ms"} {"level":"info","ts":"2026-02-18T02:51:04.715926Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9870e1635c99d2ec","cluster-id":"185398c074a8a76b"} {"level":"info","ts":"2026-02-18T02:51:04.716075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T02:51:04.71615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 0"} {"level":"info","ts":"2026-02-18T02:51:04.716203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9870e1635c99d2ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T02:51:04.716245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 1"} {"level":"info","ts":"2026-02-18T02:51:04.716322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"warn","ts":"2026-02-18T02:51:04.722606Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T02:51:04.734176Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T02:51:04.735267Z","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-18T02:51:04.738893Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9870e1635c99d2ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T02:51:04.742121Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9870e1635c99d2ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T02:51:04.74262Z","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-18T02:51:04.742735Z","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-18T02:51:04.742788Z","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-18T02:51:04.743093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"info","ts":"2026-02-18T02:51:04.743238Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","added-peer-id":"9870e1635c99d2ec","added-peer-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-02-18T02:51:04.752447Z","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-18T02:51:04.752751Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9870e1635c99d2ec","initial-advertise-peer-urls":["https://199.19.213.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T02:51:04.752836Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T02:51:04.752963Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-02-18T02:51:04.753004Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-02-18T02:51:04.816543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T02:51:04.816741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T02:51:04.81682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgPreVoteResp from 9870e1635c99d2ec at term 1"} {"level":"info","ts":"2026-02-18T02:51:04.816862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became candidate at term 2"} {"level":"info","ts":"2026-02-18T02:51:04.816934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgVoteResp from 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-02-18T02:51:04.81698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became leader at term 2"} {"level":"info","ts":"2026-02-18T02:51:04.817023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9870e1635c99d2ec elected leader 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-02-18T02:51:04.822854Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9870e1635c99d2ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.224:2379]}","request-path":"/0/members/9870e1635c99d2ec/attributes","cluster-id":"185398c074a8a76b","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T02:51:04.822998Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:51:04.823036Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:51:04.82306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:51:04.824461Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:51:04.824542Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:51:04.824633Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:51:04.823152Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T02:51:04.824712Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T02:51:04.8259Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T02:51:04.844219Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.224:2379"} {"level":"info","ts":"2026-02-18T02:51:07.235917Z","caller":"traceutil/trace.go:171","msg":"trace[960421921] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"113.088655ms","start":"2026-02-18T02:51:07.122801Z","end":"2026-02-18T02:51:07.23589Z","steps":["trace[960421921] 'process raft request' (duration: 112.959292ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.236446Z","caller":"traceutil/trace.go:171","msg":"trace[236271793] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:6; }","duration":"104.931718ms","start":"2026-02-18T02:51:07.131503Z","end":"2026-02-18T02:51:07.236435Z","steps":["trace[236271793] 'read index received' (duration: 104.920168ms)","trace[236271793] 'applied index is now lower than readState.Index' (duration: 5.3µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:07.236838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.871701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.237053Z","caller":"traceutil/trace.go:171","msg":"trace[825761348] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:3; }","duration":"114.110397ms","start":"2026-02-18T02:51:07.122898Z","end":"2026-02-18T02:51:07.237008Z","steps":["trace[825761348] 'agreement among raft nodes before linearized reading' (duration: 113.683808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.23754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.950875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.237622Z","caller":"traceutil/trace.go:171","msg":"trace[1821961564] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"116.040328ms","start":"2026-02-18T02:51:07.121569Z","end":"2026-02-18T02:51:07.23761Z","steps":["trace[1821961564] 'agreement among raft nodes before linearized reading' (duration: 115.945076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.238387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.674245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.238472Z","caller":"traceutil/trace.go:171","msg":"trace[1726642187] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:3; }","duration":"118.764038ms","start":"2026-02-18T02:51:07.119697Z","end":"2026-02-18T02:51:07.238461Z","steps":["trace[1726642187] 'agreement among raft nodes before linearized reading' (duration: 118.610274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.240153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.496565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.240253Z","caller":"traceutil/trace.go:171","msg":"trace[682509984] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:3; }","duration":"120.603037ms","start":"2026-02-18T02:51:07.119643Z","end":"2026-02-18T02:51:07.240246Z","steps":["trace[682509984] 'agreement among raft nodes before linearized reading' (duration: 120.445393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.240796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.155328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.224\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.240956Z","caller":"traceutil/trace.go:171","msg":"trace[856249789] range","detail":"{range_begin:/registry/masterleases/199.19.213.224; range_end:; response_count:0; response_revision:3; }","duration":"121.262ms","start":"2026-02-18T02:51:07.119618Z","end":"2026-02-18T02:51:07.24088Z","steps":["trace[856249789] 'agreement among raft nodes before linearized reading' (duration: 120.869782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.243329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.062069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.243521Z","caller":"traceutil/trace.go:171","msg":"trace[2026128339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:3; }","duration":"135.234142ms","start":"2026-02-18T02:51:07.108247Z","end":"2026-02-18T02:51:07.243481Z","steps":["trace[2026128339] 'agreement among raft nodes before linearized reading' (duration: 135.048019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.244174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.927798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.244325Z","caller":"traceutil/trace.go:171","msg":"trace[1911168007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:3; }","duration":"136.064061ms","start":"2026-02-18T02:51:07.108225Z","end":"2026-02-18T02:51:07.24429Z","steps":["trace[1911168007] 'agreement among raft nodes before linearized reading' (duration: 135.815665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.245187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.279118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.245351Z","caller":"traceutil/trace.go:171","msg":"trace[1281348954] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"138.410301ms","start":"2026-02-18T02:51:07.106891Z","end":"2026-02-18T02:51:07.245301Z","steps":["trace[1281348954] 'agreement among raft nodes before linearized reading' (duration: 138.123675ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.28548Z","caller":"traceutil/trace.go:171","msg":"trace[275169185] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"140.811413ms","start":"2026-02-18T02:51:07.144633Z","end":"2026-02-18T02:51:07.285444Z","steps":["trace[275169185] 'process raft request' (duration: 139.906964ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.286486Z","caller":"traceutil/trace.go:171","msg":"trace[1461419779] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"139.330781ms","start":"2026-02-18T02:51:07.147144Z","end":"2026-02-18T02:51:07.286475Z","steps":["trace[1461419779] 'process raft request' (duration: 137.816918ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.289473Z","caller":"traceutil/trace.go:171","msg":"trace[205047055] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"142.271835ms","start":"2026-02-18T02:51:07.147191Z","end":"2026-02-18T02:51:07.289463Z","steps":["trace[205047055] 'process raft request' (duration: 137.935831ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.293381Z","caller":"traceutil/trace.go:171","msg":"trace[984851534] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"146.154899ms","start":"2026-02-18T02:51:07.147212Z","end":"2026-02-18T02:51:07.293367Z","steps":["trace[984851534] 'process raft request' (duration: 142.186803ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.295997Z","caller":"traceutil/trace.go:171","msg":"trace[1521707293] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"148.740054ms","start":"2026-02-18T02:51:07.147246Z","end":"2026-02-18T02:51:07.295986Z","steps":["trace[1521707293] 'process raft request' (duration: 142.192253ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.296086Z","caller":"traceutil/trace.go:171","msg":"trace[393212176] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"148.810736ms","start":"2026-02-18T02:51:07.147269Z","end":"2026-02-18T02:51:07.29608Z","steps":["trace[393212176] 'process raft request' (duration: 146.383604ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.296244Z","caller":"traceutil/trace.go:171","msg":"trace[529493588] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"135.977158ms","start":"2026-02-18T02:51:07.160259Z","end":"2026-02-18T02:51:07.296236Z","steps":["trace[529493588] 'process raft request' (duration: 133.414212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.29647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.787625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.296501Z","caller":"traceutil/trace.go:171","msg":"trace[717353748] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"117.863177ms","start":"2026-02-18T02:51:07.178625Z","end":"2026-02-18T02:51:07.296488Z","steps":["trace[717353748] 'agreement among raft nodes before linearized reading' (duration: 117.719364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.296666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.123002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.296684Z","caller":"traceutil/trace.go:171","msg":"trace[2137366165] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"118.172003ms","start":"2026-02-18T02:51:07.178507Z","end":"2026-02-18T02:51:07.296679Z","steps":["trace[2137366165] 'agreement among raft nodes before linearized reading' (duration: 118.122242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.296784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.26318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-18T02:51:07.296798Z","caller":"traceutil/trace.go:171","msg":"trace[1564267144] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"152.297612ms","start":"2026-02-18T02:51:07.144496Z","end":"2026-02-18T02:51:07.296794Z","steps":["trace[1564267144] 'agreement among raft nodes before linearized reading' (duration: 152.265121ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.389356Z","caller":"traceutil/trace.go:171","msg":"trace[3860875] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"137.648734ms","start":"2026-02-18T02:51:07.251683Z","end":"2026-02-18T02:51:07.389332Z","steps":["trace[3860875] 'process raft request' (duration: 137.615634ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.38965Z","caller":"traceutil/trace.go:171","msg":"trace[1203479241] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"138.384451ms","start":"2026-02-18T02:51:07.251246Z","end":"2026-02-18T02:51:07.38963Z","steps":["trace[1203479241] 'process raft request' (duration: 137.989821ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.389917Z","caller":"traceutil/trace.go:171","msg":"trace[292500882] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"138.264169ms","start":"2026-02-18T02:51:07.251639Z","end":"2026-02-18T02:51:07.389903Z","steps":["trace[292500882] 'process raft request' (duration: 137.623744ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.390385Z","caller":"traceutil/trace.go:171","msg":"trace[422252272] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"144.111336ms","start":"2026-02-18T02:51:07.246261Z","end":"2026-02-18T02:51:07.390372Z","steps":["trace[422252272] 'process raft request' (duration: 123.855168ms)","trace[422252272] 'compare' (duration: 18.909098ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:07.389415Z","caller":"traceutil/trace.go:171","msg":"trace[1590326168] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"138.196587ms","start":"2026-02-18T02:51:07.25119Z","end":"2026-02-18T02:51:07.389387Z","steps":["trace[1590326168] 'process raft request' (duration: 137.988872ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.396853Z","caller":"traceutil/trace.go:171","msg":"trace[739675768] linearizableReadLoop","detail":"{readStateIndex:19; appliedIndex:13; }","duration":"103.032616ms","start":"2026-02-18T02:51:07.293729Z","end":"2026-02-18T02:51:07.396762Z","steps":["trace[739675768] 'read index received' (duration: 76.401271ms)","trace[739675768] 'applied index is now lower than readState.Index' (duration: 26.630205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:07.397246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.082277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.397301Z","caller":"traceutil/trace.go:171","msg":"trace[2116166103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"146.185448ms","start":"2026-02-18T02:51:07.251103Z","end":"2026-02-18T02:51:07.397288Z","steps":["trace[2116166103] 'agreement among raft nodes before linearized reading' (duration: 145.980054ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.397741Z","caller":"traceutil/trace.go:171","msg":"trace[2128461706] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"104.546359ms","start":"2026-02-18T02:51:07.29318Z","end":"2026-02-18T02:51:07.397726Z","steps":["trace[2128461706] 'process raft request' (duration: 103.310282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.398373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.044608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.401187Z","caller":"traceutil/trace.go:171","msg":"trace[44412544] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:19; }","duration":"149.88976ms","start":"2026-02-18T02:51:07.251284Z","end":"2026-02-18T02:51:07.401174Z","steps":["trace[44412544] 'agreement among raft nodes before linearized reading' (duration: 147.024908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.463717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.727458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-18T02:51:07.463845Z","caller":"traceutil/trace.go:171","msg":"trace[378364012] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"150.922972ms","start":"2026-02-18T02:51:07.312902Z","end":"2026-02-18T02:51:07.463825Z","steps":["trace[378364012] 'agreement among raft nodes before linearized reading' (duration: 150.627556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.464191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.713971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-9hz8t\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-02-18T02:51:07.464389Z","caller":"traceutil/trace.go:171","msg":"trace[1226748362] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-9hz8t; range_end:; response_count:1; response_revision:20; }","duration":"158.918735ms","start":"2026-02-18T02:51:07.305416Z","end":"2026-02-18T02:51:07.464335Z","steps":["trace[1226748362] 'agreement among raft nodes before linearized reading' (duration: 158.71058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.464254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.723971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.465009Z","caller":"traceutil/trace.go:171","msg":"trace[2135464204] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:20; }","duration":"110.542969ms","start":"2026-02-18T02:51:07.354451Z","end":"2026-02-18T02:51:07.464994Z","steps":["trace[2135464204] 'agreement among raft nodes before linearized reading' (duration: 109.740022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.464292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.970873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-18T02:51:07.46544Z","caller":"traceutil/trace.go:171","msg":"trace[1763018230] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"140.137729ms","start":"2026-02-18T02:51:07.325289Z","end":"2026-02-18T02:51:07.465427Z","steps":["trace[1763018230] 'agreement among raft nodes before linearized reading' (duration: 138.981933ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.579251Z","caller":"traceutil/trace.go:171","msg":"trace[269245404] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"177.789831ms","start":"2026-02-18T02:51:07.401377Z","end":"2026-02-18T02:51:07.579167Z","steps":["trace[269245404] 'process raft request' (duration: 102.917593ms)","trace[269245404] 'compare' (duration: 73.54454ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:07.58138Z","caller":"traceutil/trace.go:171","msg":"trace[1705589182] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"107.841921ms","start":"2026-02-18T02:51:07.473519Z","end":"2026-02-18T02:51:07.581361Z","steps":["trace[1705589182] 'process raft request' (duration: 107.747369ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.581679Z","caller":"traceutil/trace.go:171","msg":"trace[890833098] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"176.399523ms","start":"2026-02-18T02:51:07.405269Z","end":"2026-02-18T02:51:07.581668Z","steps":["trace[890833098] 'process raft request' (duration: 174.43187ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.581851Z","caller":"traceutil/trace.go:171","msg":"trace[677159899] linearizableReadLoop","detail":"{readStateIndex:29; appliedIndex:24; }","duration":"119.115134ms","start":"2026-02-18T02:51:07.462715Z","end":"2026-02-18T02:51:07.58183Z","steps":["trace[677159899] 'read index received' (duration: 41.696282ms)","trace[677159899] 'applied index is now lower than readState.Index' (duration: 77.416182ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:07.581932Z","caller":"traceutil/trace.go:171","msg":"trace[1924816503] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"176.400443ms","start":"2026-02-18T02:51:07.40552Z","end":"2026-02-18T02:51:07.581921Z","steps":["trace[1924816503] 'process raft request' (duration: 174.920181ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.58224Z","caller":"traceutil/trace.go:171","msg":"trace[1792189057] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"175.919121ms","start":"2026-02-18T02:51:07.4063Z","end":"2026-02-18T02:51:07.582219Z","steps":["trace[1792189057] 'process raft request' (duration: 174.600393ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.583977Z","caller":"traceutil/trace.go:171","msg":"trace[108753058] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"111.237914ms","start":"2026-02-18T02:51:07.472723Z","end":"2026-02-18T02:51:07.583961Z","steps":["trace[108753058] 'process raft request' (duration: 108.346872ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.584767Z","caller":"traceutil/trace.go:171","msg":"trace[1976923044] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"111.652153ms","start":"2026-02-18T02:51:07.473096Z","end":"2026-02-18T02:51:07.584748Z","steps":["trace[1976923044] 'process raft request' (duration: 108.053755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.585048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.537377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-18T02:51:07.585144Z","caller":"traceutil/trace.go:171","msg":"trace[325722783] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:29; }","duration":"177.69859ms","start":"2026-02-18T02:51:07.407429Z","end":"2026-02-18T02:51:07.585127Z","steps":["trace[325722783] 'agreement among raft nodes before linearized reading' (duration: 177.123128ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.581358Z","caller":"traceutil/trace.go:171","msg":"trace[227407555] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"174.060311ms","start":"2026-02-18T02:51:07.407276Z","end":"2026-02-18T02:51:07.581337Z","steps":["trace[227407555] 'process raft request' (duration: 173.712564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.585591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.578188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.585679Z","caller":"traceutil/trace.go:171","msg":"trace[1696164973] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:29; }","duration":"184.699242ms","start":"2026-02-18T02:51:07.400965Z","end":"2026-02-18T02:51:07.585664Z","steps":["trace[1696164973] 'agreement among raft nodes before linearized reading' (duration: 184.579098ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.585071Z","caller":"traceutil/trace.go:171","msg":"trace[150229032] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"111.782387ms","start":"2026-02-18T02:51:07.473265Z","end":"2026-02-18T02:51:07.585048Z","steps":["trace[150229032] 'process raft request' (duration: 107.960164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:07.586156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.88972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:07.587086Z","caller":"traceutil/trace.go:171","msg":"trace[190663799] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:29; }","duration":"113.884131ms","start":"2026-02-18T02:51:07.473186Z","end":"2026-02-18T02:51:07.58707Z","steps":["trace[190663799] 'agreement among raft nodes before linearized reading' (duration: 112.902431ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:07.890644Z","caller":"traceutil/trace.go:171","msg":"trace[1308506487] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"118.647393ms","start":"2026-02-18T02:51:07.771971Z","end":"2026-02-18T02:51:07.890619Z","steps":["trace[1308506487] 'process raft request' (duration: 55.719294ms)","trace[1308506487] 'compare' (duration: 62.755336ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:07.891379Z","caller":"traceutil/trace.go:171","msg":"trace[1058389141] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"117.169892ms","start":"2026-02-18T02:51:07.774197Z","end":"2026-02-18T02:51:07.891367Z","steps":["trace[1058389141] 'process raft request' (duration: 116.383985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:08.132987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.489855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-18T02:51:08.133197Z","caller":"traceutil/trace.go:171","msg":"trace[1384979168] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"189.468555ms","start":"2026-02-18T02:51:07.943681Z","end":"2026-02-18T02:51:08.13315Z","steps":["trace[1384979168] 'read index received' (duration: 84.820344ms)","trace[1384979168] 'applied index is now lower than readState.Index' (duration: 104.646231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:08.133466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.784892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/catch-all\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-02-18T02:51:08.13355Z","caller":"traceutil/trace.go:171","msg":"trace[1226860641] range","detail":"{range_begin:/registry/flowschemas/catch-all; range_end:; response_count:1; response_revision:72; }","duration":"189.905364ms","start":"2026-02-18T02:51:07.943627Z","end":"2026-02-18T02:51:08.133533Z","steps":["trace[1226860641] 'agreement among raft nodes before linearized reading' (duration: 189.769832ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:08.133722Z","caller":"traceutil/trace.go:171","msg":"trace[2068417428] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"202.089267ms","start":"2026-02-18T02:51:07.93162Z","end":"2026-02-18T02:51:08.133709Z","steps":["trace[2068417428] 'process raft request' (duration: 96.997326ms)","trace[2068417428] 'compare' (duration: 102.349072ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:08.13414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.753207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:08.134191Z","caller":"traceutil/trace.go:171","msg":"trace[1739993779] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:72; }","duration":"113.356839ms","start":"2026-02-18T02:51:08.020819Z","end":"2026-02-18T02:51:08.134176Z","steps":["trace[1739993779] 'agreement among raft nodes before linearized reading' (duration: 112.787817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:08.134931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.314579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:51:08.135094Z","caller":"traceutil/trace.go:171","msg":"trace[664487183] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:72; }","duration":"113.495363ms","start":"2026-02-18T02:51:08.021585Z","end":"2026-02-18T02:51:08.135081Z","steps":["trace[664487183] 'agreement among raft nodes before linearized reading' (duration: 113.316839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:13.600366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.098176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:51:13.600453Z","caller":"traceutil/trace.go:171","msg":"trace[958367323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:334; }","duration":"101.32557ms","start":"2026-02-18T02:51:13.499108Z","end":"2026-02-18T02:51:13.600434Z","steps":["trace[958367323] 'range keys from in-memory index tree' (duration: 100.984192ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:15.20621Z","caller":"traceutil/trace.go:171","msg":"trace[637991864] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"105.599832ms","start":"2026-02-18T02:51:15.100594Z","end":"2026-02-18T02:51:15.206194Z","steps":["trace[637991864] 'read index received' (duration: 104.636491ms)","trace[637991864] 'applied index is now lower than readState.Index' (duration: 962.691µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:15.20642Z","caller":"traceutil/trace.go:171","msg":"trace[92125806] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"109.735601ms","start":"2026-02-18T02:51:15.096678Z","end":"2026-02-18T02:51:15.206414Z","steps":["trace[92125806] 'process raft request' (duration: 108.598796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:15.206538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.931529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-18T02:51:15.206554Z","caller":"traceutil/trace.go:171","msg":"trace[863474864] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:369; }","duration":"105.996401ms","start":"2026-02-18T02:51:15.100552Z","end":"2026-02-18T02:51:15.206548Z","steps":["trace[863474864] 'agreement among raft nodes before linearized reading' (duration: 105.9532ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:15.246909Z","caller":"traceutil/trace.go:171","msg":"trace[989829396] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"136.174752ms","start":"2026-02-18T02:51:15.110717Z","end":"2026-02-18T02:51:15.246891Z","steps":["trace[989829396] 'process raft request' (duration: 136.139742ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:15.247174Z","caller":"traceutil/trace.go:171","msg":"trace[1614545317] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"138.264377ms","start":"2026-02-18T02:51:15.108905Z","end":"2026-02-18T02:51:15.247169Z","steps":["trace[1614545317] 'process raft request' (duration: 137.877299ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:15.372349Z","caller":"traceutil/trace.go:171","msg":"trace[1502641668] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"159.6387ms","start":"2026-02-18T02:51:15.212689Z","end":"2026-02-18T02:51:15.372327Z","steps":["trace[1502641668] 'process raft request' (duration: 136.294496ms)","trace[1502641668] 'compare' (duration: 23.201641ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:15.388235Z","caller":"traceutil/trace.go:171","msg":"trace[1100601075] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"119.691097ms","start":"2026-02-18T02:51:15.268525Z","end":"2026-02-18T02:51:15.388217Z","steps":["trace[1100601075] 'process raft request' (duration: 119.615956ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:15.388513Z","caller":"traceutil/trace.go:171","msg":"trace[1945689529] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"125.268947ms","start":"2026-02-18T02:51:15.263216Z","end":"2026-02-18T02:51:15.388485Z","steps":["trace[1945689529] 'process raft request' (duration: 124.891589ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:15.388767Z","caller":"traceutil/trace.go:171","msg":"trace[1812132983] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"125.636356ms","start":"2026-02-18T02:51:15.263119Z","end":"2026-02-18T02:51:15.388755Z","steps":["trace[1812132983] 'process raft request' (duration: 124.89302ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:18.034862Z","caller":"traceutil/trace.go:171","msg":"trace[234641578] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"315.897787ms","start":"2026-02-18T02:51:17.718949Z","end":"2026-02-18T02:51:18.034847Z","steps":["trace[234641578] 'read index received' (duration: 315.769445ms)","trace[234641578] 'applied index is now lower than readState.Index' (duration: 127.712µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:18.035075Z","caller":"traceutil/trace.go:171","msg":"trace[1044443196] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"385.710086ms","start":"2026-02-18T02:51:17.649357Z","end":"2026-02-18T02:51:18.035067Z","steps":["trace[1044443196] 'process raft request' (duration: 385.40575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:18.035139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:17.649331Z","time spent":"385.759897ms","remote":"127.0.0.1:33272","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-18T02:51:18.035261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.320317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:51:18.035277Z","caller":"traceutil/trace.go:171","msg":"trace[1085987716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:404; }","duration":"316.366657ms","start":"2026-02-18T02:51:17.718906Z","end":"2026-02-18T02:51:18.035272Z","steps":["trace[1085987716] 'agreement among raft nodes before linearized reading' (duration: 316.336547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:18.03529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:17.718889Z","time spent":"316.398509ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:51:18.035355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.713284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:51:18.035368Z","caller":"traceutil/trace.go:171","msg":"trace[179353452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:404; }","duration":"291.759655ms","start":"2026-02-18T02:51:17.743605Z","end":"2026-02-18T02:51:18.035365Z","steps":["trace[179353452] 'agreement among raft nodes before linearized reading' (duration: 291.736175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:18.035539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.01294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:51:18.035567Z","caller":"traceutil/trace.go:171","msg":"trace[1183725603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:404; }","duration":"291.05307ms","start":"2026-02-18T02:51:17.744508Z","end":"2026-02-18T02:51:18.035561Z","steps":["trace[1183725603] 'agreement among raft nodes before linearized reading' (duration: 291.01785ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:22.231562Z","caller":"traceutil/trace.go:171","msg":"trace[1026104291] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:434; }","duration":"171.370475ms","start":"2026-02-18T02:51:22.060175Z","end":"2026-02-18T02:51:22.231545Z","steps":["trace[1026104291] 'read index received' (duration: 9.723951ms)","trace[1026104291] 'applied index is now lower than readState.Index' (duration: 161.646024ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:22.231638Z","caller":"traceutil/trace.go:171","msg":"trace[609370990] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"173.51382ms","start":"2026-02-18T02:51:22.058118Z","end":"2026-02-18T02:51:22.231632Z","steps":["trace[609370990] 'process raft request' (duration: 75.936121ms)","trace[609370990] 'compare' (duration: 97.415325ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:22.231951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.754903ms","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-18T02:51:22.231913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.254301ms","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-18T02:51:22.23198Z","caller":"traceutil/trace.go:171","msg":"trace[371155975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"171.812204ms","start":"2026-02-18T02:51:22.060161Z","end":"2026-02-18T02:51:22.231973Z","steps":["trace[371155975] 'agreement among raft nodes before linearized reading' (duration: 171.743392ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:22.232024Z","caller":"traceutil/trace.go:171","msg":"trace[1861891718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"171.379733ms","start":"2026-02-18T02:51:22.060615Z","end":"2026-02-18T02:51:22.231995Z","steps":["trace[1861891718] 'agreement among raft nodes before linearized reading' (duration: 171.21321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:24.743168Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694841556438958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:51:25.244264Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694841556438958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:51:25.531921Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.453976013s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:51:25.532486Z","caller":"traceutil/trace.go:171","msg":"trace[610153889] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"1.454616946s","start":"2026-02-18T02:51:24.077838Z","end":"2026-02-18T02:51:25.532455Z","steps":["trace[610153889] 'process raft request' (duration: 1.454426702s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:25.532728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:24.07782Z","time spent":"1.454719199s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:25.759781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516648457s","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-18T02:51:25.759832Z","caller":"traceutil/trace.go:171","msg":"trace[167321823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"1.51678555s","start":"2026-02-18T02:51:24.243038Z","end":"2026-02-18T02:51:25.759823Z","steps":["trace[167321823] 'agreement among raft nodes before linearized reading' (duration: 1.516640217s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:25.75987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:24.242986Z","time spent":"1.516877132s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T02:51:25.759627Z","caller":"traceutil/trace.go:171","msg":"trace[412991317] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"1.516469574s","start":"2026-02-18T02:51:24.243099Z","end":"2026-02-18T02:51:25.759569Z","steps":["trace[412991317] 'read index received' (duration: 1.290245944s)","trace[412991317] 'applied index is now lower than readState.Index' (duration: 226.22196ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:25.76043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.507351047s","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-18T02:51:25.760465Z","caller":"traceutil/trace.go:171","msg":"trace[130398082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"1.507434758s","start":"2026-02-18T02:51:24.25302Z","end":"2026-02-18T02:51:25.760454Z","steps":["trace[130398082] 'agreement among raft nodes before linearized reading' (duration: 1.507332526s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:25.760495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:24.252995Z","time spent":"1.507490739s","remote":"127.0.0.1:33272","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-18T02:51:25.760675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.000026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:25.760696Z","caller":"traceutil/trace.go:171","msg":"trace[406900196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"147.060437ms","start":"2026-02-18T02:51:25.613629Z","end":"2026-02-18T02:51:25.76069Z","steps":["trace[406900196] 'agreement among raft nodes before linearized reading' (duration: 147.010276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:25.76082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.884872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-18T02:51:25.760838Z","caller":"traceutil/trace.go:171","msg":"trace[1382662025] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:424; }","duration":"579.941933ms","start":"2026-02-18T02:51:25.18089Z","end":"2026-02-18T02:51:25.760832Z","steps":["trace[1382662025] 'agreement among raft nodes before linearized reading' (duration: 579.898843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:25.760859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:25.180874Z","time spent":"579.980435ms","remote":"127.0.0.1:33446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4057,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-18T02:51:25.761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500005948s","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-18T02:51:25.76102Z","caller":"traceutil/trace.go:171","msg":"trace[1324620987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"1.500038909s","start":"2026-02-18T02:51:24.260973Z","end":"2026-02-18T02:51:25.761012Z","steps":["trace[1324620987] 'agreement among raft nodes before linearized reading' (duration: 1.499989428s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:25.761037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:24.260967Z","time spent":"1.50006596s","remote":"127.0.0.1:33272","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-18T02:51:26.174624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.809357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:51:26.174783Z","caller":"traceutil/trace.go:171","msg":"trace[1906589987] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"362.159127ms","start":"2026-02-18T02:51:25.812611Z","end":"2026-02-18T02:51:26.17477Z","steps":["trace[1906589987] 'process raft request' (duration: 362.089995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:26.174827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:25.812538Z","time spent":"362.27589ms","remote":"127.0.0.1:33464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3656,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:51:26.174969Z","caller":"traceutil/trace.go:171","msg":"trace[830455175] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"389.752663ms","start":"2026-02-18T02:51:25.785157Z","end":"2026-02-18T02:51:26.174909Z","steps":["trace[830455175] 'process raft request' (duration: 164.974415ms)","trace[830455175] 'compare' (duration: 223.563302ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:26.175167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:25.785131Z","time spent":"389.909936ms","remote":"127.0.0.1:33272","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-18T02:51:26.353555Z","caller":"traceutil/trace.go:171","msg":"trace[1184700566] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"168.648995ms","start":"2026-02-18T02:51:26.184881Z","end":"2026-02-18T02:51:26.35353Z","steps":["trace[1184700566] 'process raft request' (duration: 168.574453ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:26.353796Z","caller":"traceutil/trace.go:171","msg":"trace[1567888932] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"168.983182ms","start":"2026-02-18T02:51:26.184801Z","end":"2026-02-18T02:51:26.353784Z","steps":["trace[1567888932] 'process raft request' (duration: 109.741982ms)","trace[1567888932] 'compare' (duration: 58.610157ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:26.360937Z","caller":"traceutil/trace.go:171","msg":"trace[709597614] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"125.543474ms","start":"2026-02-18T02:51:26.235384Z","end":"2026-02-18T02:51:26.360928Z","steps":["trace[709597614] 'process raft request' (duration: 125.511883ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:26.361269Z","caller":"traceutil/trace.go:171","msg":"trace[1628424096] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"132.412652ms","start":"2026-02-18T02:51:26.228848Z","end":"2026-02-18T02:51:26.36126Z","steps":["trace[1628424096] 'process raft request' (duration: 131.932791ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:26.36162Z","caller":"traceutil/trace.go:171","msg":"trace[1764293796] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"128.866395ms","start":"2026-02-18T02:51:26.232744Z","end":"2026-02-18T02:51:26.36161Z","steps":["trace[1764293796] 'process raft request' (duration: 128.089629ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:26.361945Z","caller":"traceutil/trace.go:171","msg":"trace[2097588584] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"126.680148ms","start":"2026-02-18T02:51:26.235257Z","end":"2026-02-18T02:51:26.361937Z","steps":["trace[2097588584] 'process raft request' (duration: 125.601305ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:37.801631Z","caller":"traceutil/trace.go:171","msg":"trace[1233599428] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"144.906712ms","start":"2026-02-18T02:51:37.656701Z","end":"2026-02-18T02:51:37.801607Z","steps":["trace[1233599428] 'process raft request' (duration: 144.77742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:42.112679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694841556439151,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:51:42.292598Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.051695243s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:51:42.292896Z","caller":"traceutil/trace.go:171","msg":"trace[813693341] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.05203515s","start":"2026-02-18T02:51:41.240846Z","end":"2026-02-18T02:51:42.292881Z","steps":["trace[813693341] 'process raft request' (duration: 1.051950378s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:42.293145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:41.240837Z","time spent":"1.052107872s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:42.323682Z","caller":"traceutil/trace.go:171","msg":"trace[1640343388] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.079717976s","start":"2026-02-18T02:51:41.243953Z","end":"2026-02-18T02:51:42.323671Z","steps":["trace[1640343388] 'process raft request' (duration: 1.079369239s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:42.323769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:41.243906Z","time spent":"1.079820789s","remote":"127.0.0.1:33182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:42.323455Z","caller":"traceutil/trace.go:171","msg":"trace[1554737268] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"711.409323ms","start":"2026-02-18T02:51:41.612029Z","end":"2026-02-18T02:51:42.323438Z","steps":["trace[1554737268] 'read index received' (duration: 680.954589ms)","trace[1554737268] 'applied index is now lower than readState.Index' (duration: 30.453704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:42.324106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.070806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:42.324137Z","caller":"traceutil/trace.go:171","msg":"trace[2078211175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"712.101057ms","start":"2026-02-18T02:51:41.612024Z","end":"2026-02-18T02:51:42.324125Z","steps":["trace[2078211175] 'agreement among raft nodes before linearized reading' (duration: 712.051236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:42.324165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:41.611984Z","time spent":"712.173388ms","remote":"127.0.0.1:33028","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-18T02:51:42.324889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.057748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:51:42.324922Z","caller":"traceutil/trace.go:171","msg":"trace[1949271106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"419.11673ms","start":"2026-02-18T02:51:41.905796Z","end":"2026-02-18T02:51:42.324913Z","steps":["trace[1949271106] 'agreement among raft nodes before linearized reading' (duration: 419.029818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:42.324949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:41.905781Z","time spent":"419.161791ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T02:51:42.325161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.762201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:51:42.325192Z","caller":"traceutil/trace.go:171","msg":"trace[1629149290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"440.789001ms","start":"2026-02-18T02:51:41.884391Z","end":"2026-02-18T02:51:42.32518Z","steps":["trace[1629149290] 'agreement among raft nodes before linearized reading' (duration: 440.71791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:42.325219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:41.884355Z","time spent":"440.859092ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:51:42.325454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.792259ms","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-18T02:51:42.325484Z","caller":"traceutil/trace.go:171","msg":"trace[319691645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"510.85696ms","start":"2026-02-18T02:51:41.814617Z","end":"2026-02-18T02:51:42.325474Z","steps":["trace[319691645] 'agreement among raft nodes before linearized reading' (duration: 509.816878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:42.32551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:41.814599Z","time spent":"510.905681ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:51:44.853475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694841556439162,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:51:45.354093Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694841556439162,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:51:45.568256Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.294078355s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T02:51:46.61599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007670984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T02:51:46.616244Z","caller":"traceutil/trace.go:171","msg":"trace[1312198154] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.007962919s","start":"2026-02-18T02:51:44.608263Z","end":"2026-02-18T02:51:46.616226Z","steps":["trace[1312198154] 'agreement among raft nodes before linearized reading' (duration: 2.007687704s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:46.616528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:44.60825Z","time spent":"2.008094902s","remote":"127.0.0.1:33028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:51:47.091338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522916058s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:51:47.091428Z","caller":"traceutil/trace.go:171","msg":"trace[43236630] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"2.738772285s","start":"2026-02-18T02:51:44.352642Z","end":"2026-02-18T02:51:47.091414Z","steps":["trace[43236630] 'read index received' (duration: 1.215727875s)","trace[43236630] 'applied index is now lower than readState.Index' (duration: 1.52304324s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:47.091696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:46.070419Z","time spent":"1.02126648s","remote":"127.0.0.1:38986","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T02:51:47.091881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.739212284s","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-18T02:51:47.091911Z","caller":"traceutil/trace.go:171","msg":"trace[2061165618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"2.739262245s","start":"2026-02-18T02:51:44.352638Z","end":"2026-02-18T02:51:47.091901Z","steps":["trace[2061165618] 'agreement among raft nodes before linearized reading' (duration: 2.739128593s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:47.091937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:44.352605Z","time spent":"2.739326876s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:51:47.092124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.738824916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:51:47.092142Z","caller":"traceutil/trace.go:171","msg":"trace[1324857231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"2.738882217s","start":"2026-02-18T02:51:44.353254Z","end":"2026-02-18T02:51:47.092136Z","steps":["trace[1324857231] 'agreement among raft nodes before linearized reading' (duration: 2.738825216s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:47.092158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:44.353219Z","time spent":"2.738935358s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:51:47.092403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.735305562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:51:47.092475Z","caller":"traceutil/trace.go:171","msg":"trace[351291740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"2.735401255s","start":"2026-02-18T02:51:44.357055Z","end":"2026-02-18T02:51:47.092456Z","steps":["trace[351291740] 'agreement among raft nodes before linearized reading' (duration: 2.73518826s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:47.09252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:44.357046Z","time spent":"2.735467756s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T02:51:47.092816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.680032092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:51:47.092852Z","caller":"traceutil/trace.go:171","msg":"trace[34223078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"2.680093083s","start":"2026-02-18T02:51:44.412749Z","end":"2026-02-18T02:51:47.092842Z","steps":["trace[34223078] 'agreement among raft nodes before linearized reading' (duration: 2.67997875s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:47.092879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:44.412734Z","time spent":"2.680138864s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:51:47.094656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.809992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:47.094691Z","caller":"traceutil/trace.go:171","msg":"trace[1815278094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"477.903874ms","start":"2026-02-18T02:51:46.616778Z","end":"2026-02-18T02:51:47.094682Z","steps":["trace[1815278094] 'agreement among raft nodes before linearized reading' (duration: 475.578926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:47.094716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:46.616758Z","time spent":"477.951865ms","remote":"127.0.0.1:33022","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-18T02:51:47.353286Z","caller":"traceutil/trace.go:171","msg":"trace[55561655] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"256.626099ms","start":"2026-02-18T02:51:47.096638Z","end":"2026-02-18T02:51:47.353264Z","steps":["trace[55561655] 'process raft request' (duration: 256.473636ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:47.360498Z","caller":"traceutil/trace.go:171","msg":"trace[1757505891] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"260.920179ms","start":"2026-02-18T02:51:47.099546Z","end":"2026-02-18T02:51:47.360466Z","steps":["trace[1757505891] 'process raft request' (duration: 260.639413ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:47.360666Z","caller":"traceutil/trace.go:171","msg":"trace[782745172] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"261.052891ms","start":"2026-02-18T02:51:47.0996Z","end":"2026-02-18T02:51:47.360653Z","steps":["trace[782745172] 'process raft request' (duration: 260.827877ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:47.360976Z","caller":"traceutil/trace.go:171","msg":"trace[1721818015] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:551; }","duration":"252.355031ms","start":"2026-02-18T02:51:47.10861Z","end":"2026-02-18T02:51:47.360965Z","steps":["trace[1721818015] 'read index received' (duration: 245.13883ms)","trace[1721818015] 'applied index is now lower than readState.Index' (duration: 7.215541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:47.361261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.637837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:47.36169Z","caller":"traceutil/trace.go:171","msg":"trace[1756628675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"253.118146ms","start":"2026-02-18T02:51:47.108556Z","end":"2026-02-18T02:51:47.361675Z","steps":["trace[1756628675] 'agreement among raft nodes before linearized reading' (duration: 252.435282ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:47.361852Z","caller":"traceutil/trace.go:171","msg":"trace[1937506274] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"251.82636ms","start":"2026-02-18T02:51:47.110006Z","end":"2026-02-18T02:51:47.361833Z","steps":["trace[1937506274] 'process raft request' (duration: 250.8803ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:47.361329Z","caller":"traceutil/trace.go:171","msg":"trace[668252438] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"256.69953ms","start":"2026-02-18T02:51:47.104624Z","end":"2026-02-18T02:51:47.361323Z","steps":["trace[668252438] 'process raft request' (duration: 255.978085ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:48.971466Z","caller":"traceutil/trace.go:171","msg":"trace[1103135482] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"360.570172ms","start":"2026-02-18T02:51:48.610866Z","end":"2026-02-18T02:51:48.971436Z","steps":["trace[1103135482] 'read index received' (duration: 360.126733ms)","trace[1103135482] 'applied index is now lower than readState.Index' (duration: 439.189µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:48.971854Z","caller":"traceutil/trace.go:171","msg":"trace[1215450661] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"383.113072ms","start":"2026-02-18T02:51:48.58873Z","end":"2026-02-18T02:51:48.971843Z","steps":["trace[1215450661] 'process raft request' (duration: 382.53119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:48.971862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.91522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.224\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:51:48.971972Z","caller":"traceutil/trace.go:171","msg":"trace[1374658859] range","detail":"{range_begin:/registry/masterleases/199.19.213.224; range_end:; response_count:1; response_revision:536; }","duration":"164.062623ms","start":"2026-02-18T02:51:48.807887Z","end":"2026-02-18T02:51:48.97195Z","steps":["trace[1374658859] 'agreement among raft nodes before linearized reading' (duration: 163.851798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:48.971988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:48.588706Z","time spent":"383.171812ms","remote":"127.0.0.1:33272","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-18T02:51:48.972272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.40902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:48.972307Z","caller":"traceutil/trace.go:171","msg":"trace[2035693601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"361.483462ms","start":"2026-02-18T02:51:48.610804Z","end":"2026-02-18T02:51:48.972288Z","steps":["trace[2035693601] 'agreement among raft nodes before linearized reading' (duration: 361.433561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:48.972326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:48.610776Z","time spent":"361.545432ms","remote":"127.0.0.1:33028","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-18T02:51:49.15449Z","caller":"traceutil/trace.go:171","msg":"trace[1115179678] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"164.954002ms","start":"2026-02-18T02:51:48.989513Z","end":"2026-02-18T02:51:49.154467Z","steps":["trace[1115179678] 'process raft request' (duration: 100.527612ms)","trace[1115179678] 'compare' (duration: 64.329098ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:49.604203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.06085ms","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-18T02:51:49.604283Z","caller":"traceutil/trace.go:171","msg":"trace[768162310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"246.188073ms","start":"2026-02-18T02:51:49.358078Z","end":"2026-02-18T02:51:49.604266Z","steps":["trace[768162310] 'range keys from in-memory index tree' (duration: 245.794675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.604359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.233237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:51:49.60446Z","caller":"traceutil/trace.go:171","msg":"trace[233607570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"238.348879ms","start":"2026-02-18T02:51:49.366098Z","end":"2026-02-18T02:51:49.604447Z","steps":["trace[233607570] 'range keys from in-memory index tree' (duration: 238.139425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.604703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.40944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:51:49.604736Z","caller":"traceutil/trace.go:171","msg":"trace[613590738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"237.442321ms","start":"2026-02-18T02:51:49.367284Z","end":"2026-02-18T02:51:49.604726Z","steps":["trace[613590738] 'range keys from in-memory index tree' (duration: 237.318768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:49.606091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.262687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:51:49.606134Z","caller":"traceutil/trace.go:171","msg":"trace[1248412594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"238.33651ms","start":"2026-02-18T02:51:49.367786Z","end":"2026-02-18T02:51:49.606122Z","steps":["trace[1248412594] 'range keys from in-memory index tree' (duration: 236.965351ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:49.768884Z","caller":"traceutil/trace.go:171","msg":"trace[2038489732] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"152.84585ms","start":"2026-02-18T02:51:49.615991Z","end":"2026-02-18T02:51:49.768836Z","steps":["trace[2038489732] 'process raft request' (duration: 152.676646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:50.056474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.197461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:51:50.056705Z","caller":"traceutil/trace.go:171","msg":"trace[1432618935] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"438.842841ms","start":"2026-02-18T02:51:49.617846Z","end":"2026-02-18T02:51:50.056689Z","steps":["trace[1432618935] 'process raft request' (duration: 438.80492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:50.056777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:49.617839Z","time spent":"438.907762ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:50.057371Z","caller":"traceutil/trace.go:171","msg":"trace[243428970] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"441.18527ms","start":"2026-02-18T02:51:49.616167Z","end":"2026-02-18T02:51:50.057352Z","steps":["trace[243428970] 'process raft request' (duration: 333.04526ms)","trace[243428970] 'compare' (duration: 107.103628ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:50.057473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:49.616162Z","time spent":"441.271602ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:51:50.057561Z","caller":"traceutil/trace.go:171","msg":"trace[986426374] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"440.928635ms","start":"2026-02-18T02:51:49.616624Z","end":"2026-02-18T02:51:50.057552Z","steps":["trace[986426374] 'process raft request' (duration: 439.934934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:50.057605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:49.616617Z","time spent":"440.964136ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:51:54.651414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038950168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:54.651485Z","caller":"traceutil/trace.go:171","msg":"trace[291370020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"1.03906639s","start":"2026-02-18T02:51:53.612402Z","end":"2026-02-18T02:51:54.651468Z","steps":["trace[291370020] 'range keys from in-memory index tree' (duration: 1.038870104s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:54.651523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:53.612382Z","time spent":"1.039132901s","remote":"127.0.0.1:33028","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-18T02:51:54.651854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.114355ms","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-18T02:51:54.651916Z","caller":"traceutil/trace.go:171","msg":"trace[618758355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"869.204116ms","start":"2026-02-18T02:51:53.782704Z","end":"2026-02-18T02:51:54.651908Z","steps":["trace[618758355] 'range keys from in-memory index tree' (duration: 868.987592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:54.651943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:53.782687Z","time spent":"869.249668ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:51:54.652073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.74663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-18T02:51:54.652097Z","caller":"traceutil/trace.go:171","msg":"trace[866890044] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:548; }","duration":"811.839133ms","start":"2026-02-18T02:51:53.84025Z","end":"2026-02-18T02:51:54.65209Z","steps":["trace[866890044] 'range keys from in-memory index tree' (duration: 811.688399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:54.652121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:53.840236Z","time spent":"811.879503ms","remote":"127.0.0.1:33126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-18T02:51:54.652415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.94548ms","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-18T02:51:54.652441Z","caller":"traceutil/trace.go:171","msg":"trace[2136935707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"583.985402ms","start":"2026-02-18T02:51:54.068448Z","end":"2026-02-18T02:51:54.652434Z","steps":["trace[2136935707] 'range keys from in-memory index tree' (duration: 583.871149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:54.652469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.068443Z","time spent":"584.021032ms","remote":"127.0.0.1:33272","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-18T02:51:54.653112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.673666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-18T02:51:54.653178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.479258ms","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-18T02:51:54.653195Z","caller":"traceutil/trace.go:171","msg":"trace[704756252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"584.808098ms","start":"2026-02-18T02:51:54.068364Z","end":"2026-02-18T02:51:54.653172Z","steps":["trace[704756252] 'range keys from in-memory index tree' (duration: 584.40093ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:54.653212Z","caller":"traceutil/trace.go:171","msg":"trace[471932249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"580.536149ms","start":"2026-02-18T02:51:54.072666Z","end":"2026-02-18T02:51:54.653202Z","steps":["trace[471932249] 'range keys from in-memory index tree' (duration: 580.391076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:54.653244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.072656Z","time spent":"580.577529ms","remote":"127.0.0.1:33272","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-18T02:51:54.653244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.068343Z","time spent":"584.89084ms","remote":"127.0.0.1:33272","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-18T02:51:56.057373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190015989s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-18T02:51:56.084044Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.216643784s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:51:56.084398Z","caller":"traceutil/trace.go:171","msg":"trace[958779940] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.520189141s","start":"2026-02-18T02:51:54.564185Z","end":"2026-02-18T02:51:56.084374Z","steps":["trace[958779940] 'process raft request' (duration: 1.519974146s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:56.0845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.564167Z","time spent":"1.520278882s","remote":"127.0.0.1:33106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:51:56.084512Z","caller":"traceutil/trace.go:171","msg":"trace[1548856996] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"1.427785998s","start":"2026-02-18T02:51:54.656692Z","end":"2026-02-18T02:51:56.084478Z","steps":["trace[1548856996] 'read index received' (duration: 210.93199ms)","trace[1548856996] 'applied index is now lower than readState.Index' (duration: 1.216853178s)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:56.08474Z","caller":"traceutil/trace.go:171","msg":"trace[646115261] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.419400233s","start":"2026-02-18T02:51:54.66533Z","end":"2026-02-18T02:51:56.08473Z","steps":["trace[646115261] 'process raft request' (duration: 1.418953004s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:56.08481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.665311Z","time spent":"1.419456385s","remote":"127.0.0.1:33272","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-18T02:51:56.084879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428166556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:56.084962Z","caller":"traceutil/trace.go:171","msg":"trace[1238537272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"1.428273198s","start":"2026-02-18T02:51:54.656674Z","end":"2026-02-18T02:51:56.084947Z","steps":["trace[1238537272] 'agreement among raft nodes before linearized reading' (duration: 1.428160465s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:56.084996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.656662Z","time spent":"1.428326609s","remote":"127.0.0.1:33022","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-18T02:51:56.08502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408741591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:56.08505Z","caller":"traceutil/trace.go:171","msg":"trace[142381851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"1.408794032s","start":"2026-02-18T02:51:54.676246Z","end":"2026-02-18T02:51:56.08504Z","steps":["trace[142381851] 'agreement among raft nodes before linearized reading' (duration: 1.408749802s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:56.085079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.676236Z","time spent":"1.408837243s","remote":"127.0.0.1:33028","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-18T02:51:56.085127Z","caller":"traceutil/trace.go:171","msg":"trace[1839920259] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.419722639s","start":"2026-02-18T02:51:54.665394Z","end":"2026-02-18T02:51:56.085117Z","steps":["trace[1839920259] 'process raft request' (duration: 1.418987954s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:56.085185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.665387Z","time spent":"1.419761881s","remote":"127.0.0.1:33272","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-18T02:51:56.085313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.442654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8ctng\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-18T02:51:56.085325Z","caller":"traceutil/trace.go:171","msg":"trace[348815907] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.408953465s","start":"2026-02-18T02:51:54.676362Z","end":"2026-02-18T02:51:56.085316Z","steps":["trace[348815907] 'process raft request' (duration: 1.408054447s)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:56.085341Z","caller":"traceutil/trace.go:171","msg":"trace[1070714933] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8ctng; range_end:; response_count:1; response_revision:553; }","duration":"514.493114ms","start":"2026-02-18T02:51:55.570839Z","end":"2026-02-18T02:51:56.085332Z","steps":["trace[1070714933] 'agreement among raft nodes before linearized reading' (duration: 514.389952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:56.085368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:55.570826Z","time spent":"514.533945ms","remote":"127.0.0.1:33190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-8ctng\" "} {"level":"warn","ts":"2026-02-18T02:51:56.085373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.676355Z","time spent":"1.408991626s","remote":"127.0.0.1:33272","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-18T02:51:56.085474Z","caller":"traceutil/trace.go:171","msg":"trace[992863585] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.407467864s","start":"2026-02-18T02:51:54.677998Z","end":"2026-02-18T02:51:56.085466Z","steps":["trace[992863585] 'process raft request' (duration: 1.406450483s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:56.085493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407801411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-18T02:51:56.085517Z","caller":"traceutil/trace.go:171","msg":"trace[1753572332] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:553; }","duration":"1.407835882s","start":"2026-02-18T02:51:54.677674Z","end":"2026-02-18T02:51:56.08551Z","steps":["trace[1753572332] 'agreement among raft nodes before linearized reading' (duration: 1.407789791s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:56.085524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.677991Z","time spent":"1.407505395s","remote":"127.0.0.1:33272","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-18T02:51:56.085542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:54.677669Z","time spent":"1.407867283s","remote":"127.0.0.1:33124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6557,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"info","ts":"2026-02-18T02:51:56.263929Z","caller":"traceutil/trace.go:171","msg":"trace[494679223] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"160.673843ms","start":"2026-02-18T02:51:56.103237Z","end":"2026-02-18T02:51:56.263911Z","steps":["trace[494679223] 'process raft request' (duration: 160.567841ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:57.799988Z","caller":"traceutil/trace.go:171","msg":"trace[1682285024] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"190.578165ms","start":"2026-02-18T02:51:57.609389Z","end":"2026-02-18T02:51:57.799967Z","steps":["trace[1682285024] 'read index received' (duration: 65.200427ms)","trace[1682285024] 'applied index is now lower than readState.Index' (duration: 125.376848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:51:57.800174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.708518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:51:57.800208Z","caller":"traceutil/trace.go:171","msg":"trace[518859855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"190.835411ms","start":"2026-02-18T02:51:57.609363Z","end":"2026-02-18T02:51:57.800198Z","steps":["trace[518859855] 'agreement among raft nodes before linearized reading' (duration: 190.692028ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:57.800673Z","caller":"traceutil/trace.go:171","msg":"trace[931303216] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"204.486845ms","start":"2026-02-18T02:51:57.596156Z","end":"2026-02-18T02:51:57.800642Z","steps":["trace[931303216] 'process raft request' (duration: 203.674067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.413812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.609795ms","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-18T02:51:58.413856Z","caller":"traceutil/trace.go:171","msg":"trace[154917854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"313.685857ms","start":"2026-02-18T02:51:58.10016Z","end":"2026-02-18T02:51:58.413846Z","steps":["trace[154917854] 'range keys from in-memory index tree' (duration: 313.535644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.413879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.10015Z","time spent":"313.724768ms","remote":"127.0.0.1:33272","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-18T02:51:58.414078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.693456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-18T02:51:58.413812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.894412ms","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-18T02:51:58.414114Z","caller":"traceutil/trace.go:171","msg":"trace[191487478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"315.236109ms","start":"2026-02-18T02:51:58.098872Z","end":"2026-02-18T02:51:58.414108Z","steps":["trace[191487478] 'range keys from in-memory index tree' (duration: 314.73745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.414129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.09886Z","time spent":"315.26473ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T02:51:58.41412Z","caller":"traceutil/trace.go:171","msg":"trace[1653552103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"323.754797ms","start":"2026-02-18T02:51:58.090346Z","end":"2026-02-18T02:51:58.414101Z","steps":["trace[1653552103] 'range keys from in-memory index tree' (duration: 323.598284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.41415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.090327Z","time spent":"323.815588ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:51:58.414257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.361546ms","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-18T02:51:58.414272Z","caller":"traceutil/trace.go:171","msg":"trace[1940194305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"321.397538ms","start":"2026-02-18T02:51:58.092869Z","end":"2026-02-18T02:51:58.414266Z","steps":["trace[1940194305] 'range keys from in-memory index tree' (duration: 321.298215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:51:58.414286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:51:58.092858Z","time spent":"321.424868ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T02:51:59.005233Z","caller":"traceutil/trace.go:171","msg":"trace[828216416] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"102.33975ms","start":"2026-02-18T02:51:58.902871Z","end":"2026-02-18T02:51:59.005211Z","steps":["trace[828216416] 'process raft request' (duration: 51.320318ms)","trace[828216416] 'compare' (duration: 50.92267ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:51:59.756541Z","caller":"traceutil/trace.go:171","msg":"trace[309866753] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"154.018124ms","start":"2026-02-18T02:51:59.602505Z","end":"2026-02-18T02:51:59.756523Z","steps":["trace[309866753] 'process raft request' (duration: 153.912942ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:51:59.768497Z","caller":"traceutil/trace.go:171","msg":"trace[1525430524] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"163.785697ms","start":"2026-02-18T02:51:59.604699Z","end":"2026-02-18T02:51:59.768484Z","steps":["trace[1525430524] 'process raft request' (duration: 163.665565ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:02.056706Z","caller":"traceutil/trace.go:171","msg":"trace[962110885] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"177.152766ms","start":"2026-02-18T02:52:01.879532Z","end":"2026-02-18T02:52:02.056685Z","steps":["trace[962110885] 'process raft request' (duration: 176.999213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.047421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.238933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:04.047483Z","caller":"traceutil/trace.go:171","msg":"trace[975847304] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"175.090714ms","start":"2026-02-18T02:52:03.872382Z","end":"2026-02-18T02:52:04.047473Z","steps":["trace[975847304] 'read index received' (duration: 15.742648ms)","trace[975847304] 'applied index is now lower than readState.Index' (duration: 159.347436ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:04.047531Z","caller":"traceutil/trace.go:171","msg":"trace[1731307919] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"284.380608ms","start":"2026-02-18T02:52:03.763145Z","end":"2026-02-18T02:52:04.047526Z","steps":["trace[1731307919] 'process raft request' (duration: 124.988521ms)","trace[1731307919] 'compare' (duration: 159.154882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:04.047622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.973301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:04.047695Z","caller":"traceutil/trace.go:171","msg":"trace[693062232] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:639; }","duration":"175.063633ms","start":"2026-02-18T02:52:03.872618Z","end":"2026-02-18T02:52:04.047682Z","steps":["trace[693062232] 'agreement among raft nodes before linearized reading' (duration: 174.946081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.047703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.081946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:04.047721Z","caller":"traceutil/trace.go:171","msg":"trace[1511686462] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:639; }","duration":"166.124838ms","start":"2026-02-18T02:52:03.881591Z","end":"2026-02-18T02:52:04.047715Z","steps":["trace[1511686462] 'agreement among raft nodes before linearized reading' (duration: 166.091957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.047814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.431001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:04.04783Z","caller":"traceutil/trace.go:171","msg":"trace[2039864563] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:639; }","duration":"175.445891ms","start":"2026-02-18T02:52:03.872378Z","end":"2026-02-18T02:52:04.047824Z","steps":["trace[2039864563] 'agreement among raft nodes before linearized reading' (duration: 175.42306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.047849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.750735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-18T02:52:04.047868Z","caller":"traceutil/trace.go:171","msg":"trace[916552911] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:639; }","duration":"172.779086ms","start":"2026-02-18T02:52:03.875083Z","end":"2026-02-18T02:52:04.047862Z","steps":["trace[916552911] 'agreement among raft nodes before linearized reading' (duration: 172.699474ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:04.721669Z","caller":"traceutil/trace.go:171","msg":"trace[1069873444] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:664; }","duration":"447.179923ms","start":"2026-02-18T02:52:04.274473Z","end":"2026-02-18T02:52:04.721653Z","steps":["trace[1069873444] 'read index received' (duration: 423.92441ms)","trace[1069873444] 'applied index is now lower than readState.Index' (duration: 23.254873ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:04.721745Z","caller":"traceutil/trace.go:171","msg":"trace[1519756365] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"651.051196ms","start":"2026-02-18T02:52:04.070687Z","end":"2026-02-18T02:52:04.721738Z","steps":["trace[1519756365] 'process raft request' (duration: 650.77319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.722041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.544332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-18T02:52:04.722109Z","caller":"traceutil/trace.go:171","msg":"trace[1809628099] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:641; }","duration":"447.645783ms","start":"2026-02-18T02:52:04.274443Z","end":"2026-02-18T02:52:04.722089Z","steps":["trace[1809628099] 'agreement among raft nodes before linearized reading' (duration: 447.440709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.722148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:04.274429Z","time spent":"447.710414ms","remote":"127.0.0.1:33064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-02-18T02:52:04.722477Z","caller":"traceutil/trace.go:171","msg":"trace[27315495] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"616.464997ms","start":"2026-02-18T02:52:04.106Z","end":"2026-02-18T02:52:04.722465Z","steps":["trace[27315495] 'process raft request' (duration: 615.593568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.722586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:04.10598Z","time spent":"616.55989ms","remote":"127.0.0.1:33064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:52:04.723243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:04.070671Z","time spent":"651.090287ms","remote":"127.0.0.1:33182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4129,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:04.723458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.163013ms","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-18T02:52:04.723482Z","caller":"traceutil/trace.go:171","msg":"trace[836838529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"260.215495ms","start":"2026-02-18T02:52:04.463258Z","end":"2026-02-18T02:52:04.723473Z","steps":["trace[836838529] 'agreement among raft nodes before linearized reading' (duration: 260.129163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.723507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.84436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:04.723561Z","caller":"traceutil/trace.go:171","msg":"trace[1581139640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"115.922461ms","start":"2026-02-18T02:52:04.607624Z","end":"2026-02-18T02:52:04.723547Z","steps":["trace[1581139640] 'agreement among raft nodes before linearized reading' (duration: 115.843819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.723616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.890902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:52:04.723637Z","caller":"traceutil/trace.go:171","msg":"trace[1703143427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"228.926054ms","start":"2026-02-18T02:52:04.494705Z","end":"2026-02-18T02:52:04.723631Z","steps":["trace[1703143427] 'agreement among raft nodes before linearized reading' (duration: 228.869403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.723787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.232454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:52:04.723811Z","caller":"traceutil/trace.go:171","msg":"trace[374077709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"234.264934ms","start":"2026-02-18T02:52:04.489538Z","end":"2026-02-18T02:52:04.723803Z","steps":["trace[374077709] 'agreement among raft nodes before linearized reading' (duration: 234.168612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.723926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.517641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:52:04.723944Z","caller":"traceutil/trace.go:171","msg":"trace[1479068721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"236.550831ms","start":"2026-02-18T02:52:04.487387Z","end":"2026-02-18T02:52:04.723938Z","steps":["trace[1479068721] 'agreement among raft nodes before linearized reading' (duration: 236.50136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:04.724345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.860318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-18T02:52:04.724385Z","caller":"traceutil/trace.go:171","msg":"trace[278861851] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:641; }","duration":"248.92569ms","start":"2026-02-18T02:52:04.475446Z","end":"2026-02-18T02:52:04.724372Z","steps":["trace[278861851] 'agreement among raft nodes before linearized reading' (duration: 248.828868ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:11.041803Z","caller":"traceutil/trace.go:171","msg":"trace[2056862790] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"186.254825ms","start":"2026-02-18T02:52:10.855533Z","end":"2026-02-18T02:52:11.041788Z","steps":["trace[2056862790] 'process raft request' (duration: 186.039971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:11.422783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.60875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:52:11.423021Z","caller":"traceutil/trace.go:171","msg":"trace[807768282] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"372.45995ms","start":"2026-02-18T02:52:11.050545Z","end":"2026-02-18T02:52:11.423005Z","steps":["trace[807768282] 'process raft request' (duration: 372.428949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:11.42308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:11.050538Z","time spent":"372.513871ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:11.423346Z","caller":"traceutil/trace.go:171","msg":"trace[1206791848] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"375.015362ms","start":"2026-02-18T02:52:11.048318Z","end":"2026-02-18T02:52:11.423333Z","steps":["trace[1206791848] 'process raft request' (duration: 140.791489ms)","trace[1206791848] 'compare' (duration: 233.453367ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:11.423396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:11.048303Z","time spent":"375.067134ms","remote":"127.0.0.1:33272","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-18T02:52:11.423508Z","caller":"traceutil/trace.go:171","msg":"trace[1857015186] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"375.065184ms","start":"2026-02-18T02:52:11.048433Z","end":"2026-02-18T02:52:11.423498Z","steps":["trace[1857015186] 'process raft request' (duration: 374.465721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:11.42355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:11.048428Z","time spent":"375.100304ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:11.423653Z","caller":"traceutil/trace.go:171","msg":"trace[1201328104] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"375.158746ms","start":"2026-02-18T02:52:11.048487Z","end":"2026-02-18T02:52:11.423646Z","steps":["trace[1201328104] 'process raft request' (duration: 374.459691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:11.423696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:11.048482Z","time spent":"375.190306ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:12.156897Z","caller":"traceutil/trace.go:171","msg":"trace[1359448784] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"411.423999ms","start":"2026-02-18T02:52:11.745451Z","end":"2026-02-18T02:52:12.156875Z","steps":["trace[1359448784] 'process raft request' (duration: 411.312557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:12.157008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:11.745435Z","time spent":"411.517732ms","remote":"127.0.0.1:33182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:21.514819Z","caller":"traceutil/trace.go:171","msg":"trace[854521409] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"438.999644ms","start":"2026-02-18T02:52:21.075802Z","end":"2026-02-18T02:52:21.514801Z","steps":["trace[854521409] 'process raft request' (duration: 438.733149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:21.514939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:21.07579Z","time spent":"439.085316ms","remote":"127.0.0.1:33272","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-18T02:52:22.112304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694841556439634,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:52:22.351247Z","caller":"traceutil/trace.go:171","msg":"trace[2129600254] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"826.582479ms","start":"2026-02-18T02:52:21.524647Z","end":"2026-02-18T02:52:22.35123Z","steps":["trace[2129600254] 'process raft request' (duration: 813.828484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:22.35135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:21.52463Z","time spent":"826.66245ms","remote":"127.0.0.1:33272","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-18T02:52:22.408637Z","caller":"traceutil/trace.go:171","msg":"trace[2041201455] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:792; }","duration":"797.197287ms","start":"2026-02-18T02:52:21.611407Z","end":"2026-02-18T02:52:22.408605Z","steps":["trace[2041201455] 'read index received' (duration: 727.081238ms)","trace[2041201455] 'applied index is now lower than readState.Index' (duration: 70.115429ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:22.40868Z","caller":"traceutil/trace.go:171","msg":"trace[875469104] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"883.864361ms","start":"2026-02-18T02:52:21.524787Z","end":"2026-02-18T02:52:22.408651Z","steps":["trace[875469104] 'process raft request' (duration: 883.650747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:22.408739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.331161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:22.408771Z","caller":"traceutil/trace.go:171","msg":"trace[359908735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"797.413493ms","start":"2026-02-18T02:52:21.611351Z","end":"2026-02-18T02:52:22.408765Z","steps":["trace[359908735] 'agreement among raft nodes before linearized reading' (duration: 797.334141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:22.408792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:21.524782Z","time spent":"883.950993ms","remote":"127.0.0.1:33272","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-18T02:52:22.408795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:21.611331Z","time spent":"797.458713ms","remote":"127.0.0.1:33028","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-18T02:52:22.408986Z","caller":"traceutil/trace.go:171","msg":"trace[101407647] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"882.528773ms","start":"2026-02-18T02:52:21.526452Z","end":"2026-02-18T02:52:22.408981Z","steps":["trace[101407647] 'process raft request' (duration: 882.072274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:22.409036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:21.526446Z","time spent":"882.556613ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:22.409115Z","caller":"traceutil/trace.go:171","msg":"trace[1922983727] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"879.538381ms","start":"2026-02-18T02:52:21.529569Z","end":"2026-02-18T02:52:22.409107Z","steps":["trace[1922983727] 'process raft request' (duration: 878.987009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:22.409183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:21.529561Z","time spent":"879.572251ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:24.625103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096354761s","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-18T02:52:24.625156Z","caller":"traceutil/trace.go:171","msg":"trace[254964017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"1.096437743s","start":"2026-02-18T02:52:23.528706Z","end":"2026-02-18T02:52:24.625144Z","steps":["trace[254964017] 'range keys from in-memory index tree' (duration: 1.096233939s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:24.625182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:23.528691Z","time spent":"1.096485613s","remote":"127.0.0.1:33272","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-18T02:52:24.625308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015254714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:24.625341Z","caller":"traceutil/trace.go:171","msg":"trace[1873766299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"1.015296564s","start":"2026-02-18T02:52:23.610023Z","end":"2026-02-18T02:52:24.62532Z","steps":["trace[1873766299] 'range keys from in-memory index tree' (duration: 1.015208563s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:24.625358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:23.610008Z","time spent":"1.015345125s","remote":"127.0.0.1:33028","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-18T02:52:24.647984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.591993ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:52:24.648144Z","caller":"traceutil/trace.go:171","msg":"trace[1117260447] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"351.645117ms","start":"2026-02-18T02:52:24.296483Z","end":"2026-02-18T02:52:24.648129Z","steps":["trace[1117260447] 'read index received' (duration: 238.85618ms)","trace[1117260447] 'applied index is now lower than readState.Index' (duration: 112.787287ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:24.64826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.763389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:24.648283Z","caller":"traceutil/trace.go:171","msg":"trace[1124518907] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:766; }","duration":"351.82477ms","start":"2026-02-18T02:52:24.296452Z","end":"2026-02-18T02:52:24.648277Z","steps":["trace[1124518907] 'agreement among raft nodes before linearized reading' (duration: 351.751249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:24.648306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:24.296436Z","time spent":"351.864622ms","remote":"127.0.0.1:33148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:52:24.648351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.352889ms","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-18T02:52:24.64837Z","caller":"traceutil/trace.go:171","msg":"trace[1325006568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"288.40102ms","start":"2026-02-18T02:52:24.359962Z","end":"2026-02-18T02:52:24.648363Z","steps":["trace[1325006568] 'agreement among raft nodes before linearized reading' (duration: 288.336509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:24.648516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.745474ms","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-18T02:52:24.64853Z","caller":"traceutil/trace.go:171","msg":"trace[1215534918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"233.775845ms","start":"2026-02-18T02:52:24.41475Z","end":"2026-02-18T02:52:24.648526Z","steps":["trace[1215534918] 'agreement among raft nodes before linearized reading' (duration: 233.740324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:24.648626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.377448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:52:24.64865Z","caller":"traceutil/trace.go:171","msg":"trace[1221513261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"235.399489ms","start":"2026-02-18T02:52:24.413242Z","end":"2026-02-18T02:52:24.648641Z","steps":["trace[1221513261] 'agreement among raft nodes before linearized reading' (duration: 235.292426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:24.648733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.044221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:52:24.64875Z","caller":"traceutil/trace.go:171","msg":"trace[1298917369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"235.061052ms","start":"2026-02-18T02:52:24.413684Z","end":"2026-02-18T02:52:24.648745Z","steps":["trace[1298917369] 'agreement among raft nodes before linearized reading' (duration: 235.016011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:36.039553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117063143s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:52:36.039643Z","caller":"traceutil/trace.go:171","msg":"trace[770408109] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"1.426947641s","start":"2026-02-18T02:52:34.612681Z","end":"2026-02-18T02:52:36.039629Z","steps":["trace[770408109] 'read index received' (duration: 309.835647ms)","trace[770408109] 'applied index is now lower than readState.Index' (duration: 1.117111134s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:36.040082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427400431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:36.040108Z","caller":"traceutil/trace.go:171","msg":"trace[1732148691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"1.427563494s","start":"2026-02-18T02:52:34.612537Z","end":"2026-02-18T02:52:36.040101Z","steps":["trace[1732148691] 'agreement among raft nodes before linearized reading' (duration: 1.427510753s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:36.040126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:34.612488Z","time spent":"1.427634026s","remote":"127.0.0.1:33028","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-18T02:52:36.040317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340924621s","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-18T02:52:36.040336Z","caller":"traceutil/trace.go:171","msg":"trace[281754486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"1.340985563s","start":"2026-02-18T02:52:34.699346Z","end":"2026-02-18T02:52:36.040331Z","steps":["trace[281754486] 'agreement among raft nodes before linearized reading' (duration: 1.340892801s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:36.040348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:34.699317Z","time spent":"1.341029063s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:52:36.040475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270768512s","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-18T02:52:36.040493Z","caller":"traceutil/trace.go:171","msg":"trace[1132326949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"1.270807012s","start":"2026-02-18T02:52:34.769682Z","end":"2026-02-18T02:52:36.040489Z","steps":["trace[1132326949] 'agreement among raft nodes before linearized reading' (duration: 1.270760501s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:36.040506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:34.769672Z","time spent":"1.270831142s","remote":"127.0.0.1:33272","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-18T02:52:36.040603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278681855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:52:36.040621Z","caller":"traceutil/trace.go:171","msg":"trace[1285665217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"1.278713836s","start":"2026-02-18T02:52:34.761904Z","end":"2026-02-18T02:52:36.040618Z","steps":["trace[1285665217] 'agreement among raft nodes before linearized reading' (duration: 1.278669995s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:36.040633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:34.761897Z","time spent":"1.278733506s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T02:52:36.040713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285836175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:52:36.040731Z","caller":"traceutil/trace.go:171","msg":"trace[352046572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"1.285872095s","start":"2026-02-18T02:52:34.754855Z","end":"2026-02-18T02:52:36.040727Z","steps":["trace[352046572] 'agreement among raft nodes before linearized reading' (duration: 1.285831254s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:36.040743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:34.754847Z","time spent":"1.285893335s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:52:36.04086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303473472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:52:36.040877Z","caller":"traceutil/trace.go:171","msg":"trace[1367041019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"1.303512422s","start":"2026-02-18T02:52:34.737361Z","end":"2026-02-18T02:52:36.040874Z","steps":["trace[1367041019] 'agreement among raft nodes before linearized reading' (duration: 1.303450151s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:36.040889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:34.737349Z","time spent":"1.303537812s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T02:52:36.556293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694841556439734,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:52:36.707816Z","caller":"traceutil/trace.go:171","msg":"trace[1748274560] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"656.817106ms","start":"2026-02-18T02:52:36.050902Z","end":"2026-02-18T02:52:36.707719Z","steps":["trace[1748274560] 'process raft request' (duration: 656.632752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:36.708235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:36.050887Z","time spent":"657.02672ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:37.679161Z","caller":"traceutil/trace.go:171","msg":"trace[1278840218] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:836; }","duration":"1.623698264s","start":"2026-02-18T02:52:36.055434Z","end":"2026-02-18T02:52:37.679132Z","steps":["trace[1278840218] 'read index received' (duration: 652.719541ms)","trace[1278840218] 'applied index is now lower than readState.Index' (duration: 970.977563ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:37.679466Z","caller":"traceutil/trace.go:171","msg":"trace[323340193] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"1.627116895s","start":"2026-02-18T02:52:36.052335Z","end":"2026-02-18T02:52:37.679452Z","steps":["trace[323340193] 'process raft request' (duration: 1.605497536s)","trace[323340193] 'compare' (duration: 19.989686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:37.679602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:36.052326Z","time spent":"1.627185157s","remote":"127.0.0.1:33272","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-18T02:52:37.679812Z","caller":"traceutil/trace.go:171","msg":"trace[987207828] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.625127284s","start":"2026-02-18T02:52:36.054671Z","end":"2026-02-18T02:52:37.679798Z","steps":["trace[987207828] 'process raft request' (duration: 1.624330907s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.679863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:36.054664Z","time spent":"1.625169834s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:52:37.680013Z","caller":"traceutil/trace.go:171","msg":"trace[1490626586] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"1.624738766s","start":"2026-02-18T02:52:36.055263Z","end":"2026-02-18T02:52:37.680002Z","steps":["trace[1490626586] 'process raft request' (duration: 1.623792246s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.680067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:36.055255Z","time spent":"1.624780576s","remote":"127.0.0.1:33272","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-18T02:52:37.68019Z","caller":"traceutil/trace.go:171","msg":"trace[1302026210] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.624700104s","start":"2026-02-18T02:52:36.055481Z","end":"2026-02-18T02:52:37.680181Z","steps":["trace[1302026210] 'process raft request' (duration: 1.623609832s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.680235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:36.055474Z","time spent":"1.624736376s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:52:37.683185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602276527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:37.683252Z","caller":"traceutil/trace.go:171","msg":"trace[215183103] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:804; }","duration":"1.6023728s","start":"2026-02-18T02:52:36.080866Z","end":"2026-02-18T02:52:37.683239Z","steps":["trace[215183103] 'agreement among raft nodes before linearized reading' (duration: 1.602270167s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.683289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:36.080854Z","time spent":"1.602426322s","remote":"127.0.0.1:33248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:52:37.683594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.840964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T02:52:37.68038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624940709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:37.683786Z","caller":"traceutil/trace.go:171","msg":"trace[1517857930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"1.628357592s","start":"2026-02-18T02:52:36.055418Z","end":"2026-02-18T02:52:37.683776Z","steps":["trace[1517857930] 'agreement among raft nodes before linearized reading' (duration: 1.62492783s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.683816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:36.055414Z","time spent":"1.628394612s","remote":"127.0.0.1:33028","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-18T02:52:37.683617Z","caller":"traceutil/trace.go:171","msg":"trace[960203906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"454.913895ms","start":"2026-02-18T02:52:37.228695Z","end":"2026-02-18T02:52:37.683608Z","steps":["trace[960203906] 'agreement among raft nodes before linearized reading' (duration: 454.879535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:37.690602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:37.228648Z","time spent":"461.782268ms","remote":"127.0.0.1:33028","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-18T02:52:39.801473Z","caller":"traceutil/trace.go:171","msg":"trace[1420288410] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:843; }","duration":"189.060614ms","start":"2026-02-18T02:52:39.612393Z","end":"2026-02-18T02:52:39.801453Z","steps":["trace[1420288410] 'read index received' (duration: 188.895111ms)","trace[1420288410] 'applied index is now lower than readState.Index' (duration: 164.783µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:39.80175Z","caller":"traceutil/trace.go:171","msg":"trace[902538959] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"226.045143ms","start":"2026-02-18T02:52:39.575694Z","end":"2026-02-18T02:52:39.801739Z","steps":["trace[902538959] 'process raft request' (duration: 225.639065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:39.801945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.546314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:39.801975Z","caller":"traceutil/trace.go:171","msg":"trace[1355028855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"189.620776ms","start":"2026-02-18T02:52:39.612345Z","end":"2026-02-18T02:52:39.801966Z","steps":["trace[1355028855] 'agreement among raft nodes before linearized reading' (duration: 189.559935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:39.802168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.379356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-18T02:52:39.802589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.755871ms","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-18T02:52:39.802612Z","caller":"traceutil/trace.go:171","msg":"trace[607925992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:807; }","duration":"106.789392ms","start":"2026-02-18T02:52:39.695816Z","end":"2026-02-18T02:52:39.802606Z","steps":["trace[607925992] 'agreement among raft nodes before linearized reading' (duration: 106.72371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:39.802726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:52:39.802745Z","caller":"traceutil/trace.go:171","msg":"trace[1430875258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:807; }","duration":"106.246621ms","start":"2026-02-18T02:52:39.696492Z","end":"2026-02-18T02:52:39.802739Z","steps":["trace[1430875258] 'agreement among raft nodes before linearized reading' (duration: 106.193329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:39.803241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.267952ms","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-18T02:52:39.803262Z","caller":"traceutil/trace.go:171","msg":"trace[1460136428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:807; }","duration":"108.299213ms","start":"2026-02-18T02:52:39.694956Z","end":"2026-02-18T02:52:39.803256Z","steps":["trace[1460136428] 'agreement among raft nodes before linearized reading' (duration: 108.244612ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:39.803402Z","caller":"traceutil/trace.go:171","msg":"trace[265175390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:807; }","duration":"110.431878ms","start":"2026-02-18T02:52:39.691754Z","end":"2026-02-18T02:52:39.802186Z","steps":["trace[265175390] 'agreement among raft nodes before linearized reading' (duration: 110.337336ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:39.95156Z","caller":"traceutil/trace.go:171","msg":"trace[355954545] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"144.425304ms","start":"2026-02-18T02:52:39.807106Z","end":"2026-02-18T02:52:39.951531Z","steps":["trace[355954545] 'process raft request' (duration: 108.446866ms)","trace[355954545] 'compare' (duration: 35.515769ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:39.9523Z","caller":"traceutil/trace.go:171","msg":"trace[1826143232] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"141.79307ms","start":"2026-02-18T02:52:39.810496Z","end":"2026-02-18T02:52:39.952289Z","steps":["trace[1826143232] 'process raft request' (duration: 140.969893ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:39.952924Z","caller":"traceutil/trace.go:171","msg":"trace[1762384421] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"142.372712ms","start":"2026-02-18T02:52:39.810532Z","end":"2026-02-18T02:52:39.952905Z","steps":["trace[1762384421] 'process raft request' (duration: 141.531864ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:39.95353Z","caller":"traceutil/trace.go:171","msg":"trace[1136355390] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"141.994845ms","start":"2026-02-18T02:52:39.811522Z","end":"2026-02-18T02:52:39.953517Z","steps":["trace[1136355390] 'process raft request' (duration: 140.715738ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:52.139172Z","caller":"traceutil/trace.go:171","msg":"trace[1319590975] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"124.956881ms","start":"2026-02-18T02:52:52.014201Z","end":"2026-02-18T02:52:52.139158Z","steps":["trace[1319590975] 'process raft request' (duration: 124.637264ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:53.18514Z","caller":"traceutil/trace.go:171","msg":"trace[1289184266] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"280.221086ms","start":"2026-02-18T02:52:52.904894Z","end":"2026-02-18T02:52:53.185115Z","steps":["trace[1289184266] 'process raft request' (duration: 280.062242ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:55.579026Z","caller":"traceutil/trace.go:171","msg":"trace[1577956517] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"234.014603ms","start":"2026-02-18T02:52:55.344989Z","end":"2026-02-18T02:52:55.579004Z","steps":["trace[1577956517] 'read index received' (duration: 233.806669ms)","trace[1577956517] 'applied index is now lower than readState.Index' (duration: 206.964µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:55.57914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.127896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:52:55.57917Z","caller":"traceutil/trace.go:171","msg":"trace[708569606] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:906; }","duration":"234.198927ms","start":"2026-02-18T02:52:55.344963Z","end":"2026-02-18T02:52:55.579162Z","steps":["trace[708569606] 'agreement among raft nodes before linearized reading' (duration: 234.125515ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:55.57922Z","caller":"traceutil/trace.go:171","msg":"trace[1613276973] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"388.953842ms","start":"2026-02-18T02:52:55.190248Z","end":"2026-02-18T02:52:55.579202Z","steps":["trace[1613276973] 'process raft request' (duration: 388.619064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:55.579306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:55.19024Z","time spent":"389.019903ms","remote":"127.0.0.1:33272","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-18T02:52:56.871269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.070992ms","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":"warn","ts":"2026-02-18T02:52:56.871301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.138842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-18T02:52:56.871341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260012657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:56.871357Z","caller":"traceutil/trace.go:171","msg":"trace[1411194812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"1.260050978s","start":"2026-02-18T02:52:55.611302Z","end":"2026-02-18T02:52:56.871353Z","steps":["trace[1411194812] 'range keys from in-memory index tree' (duration: 1.259972226s)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:56.871353Z","caller":"traceutil/trace.go:171","msg":"trace[1061909801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:906; }","duration":"455.205624ms","start":"2026-02-18T02:52:56.416133Z","end":"2026-02-18T02:52:56.871338Z","steps":["trace[1061909801] 'range keys from in-memory index tree' (duration: 455.01422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.871378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:55.611288Z","time spent":"1.260085208s","remote":"127.0.0.1:33028","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-18T02:52:56.871384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:56.416128Z","time spent":"455.249586ms","remote":"127.0.0.1:33272","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-18T02:52:56.871739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.797517ms","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-18T02:52:56.871759Z","caller":"traceutil/trace.go:171","msg":"trace[835219401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:906; }","duration":"424.823248ms","start":"2026-02-18T02:52:56.44693Z","end":"2026-02-18T02:52:56.871753Z","steps":["trace[835219401] 'range keys from in-memory index tree' (duration: 424.749266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.871777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:56.446928Z","time spent":"424.845118ms","remote":"127.0.0.1:33272","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-18T02:52:56.871813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.427342ms","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-18T02:52:56.871829Z","caller":"traceutil/trace.go:171","msg":"trace[730389759] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:906; }","duration":"793.489384ms","start":"2026-02-18T02:52:56.078334Z","end":"2026-02-18T02:52:56.871823Z","steps":["trace[730389759] 'count revisions from in-memory index tree' (duration: 793.380741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.871845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:56.078319Z","time spent":"793.521985ms","remote":"127.0.0.1:33166","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-18T02:52:56.871881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.995241ms","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-18T02:52:56.8719Z","caller":"traceutil/trace.go:171","msg":"trace[243988678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"425.034272ms","start":"2026-02-18T02:52:56.44686Z","end":"2026-02-18T02:52:56.871894Z","steps":["trace[243988678] 'range keys from in-memory index tree' (duration: 424.875399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.871916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:56.446848Z","time spent":"425.063533ms","remote":"127.0.0.1:33272","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-18T02:52:56.872009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.868531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:52:56.872025Z","caller":"traceutil/trace.go:171","msg":"trace[642761827] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:906; }","duration":"456.905621ms","start":"2026-02-18T02:52:56.415115Z","end":"2026-02-18T02:52:56.87202Z","steps":["trace[642761827] 'count revisions from in-memory index tree' (duration: 456.82829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.872039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:56.415102Z","time spent":"456.932883ms","remote":"127.0.0.1:33454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-02-18T02:52:56.871319Z","caller":"traceutil/trace.go:171","msg":"trace[461828994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:906; }","duration":"455.140533ms","start":"2026-02-18T02:52:56.416165Z","end":"2026-02-18T02:52:56.871305Z","steps":["trace[461828994] 'range keys from in-memory index tree' (duration: 454.986269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.872151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:56.416162Z","time spent":"455.982321ms","remote":"127.0.0.1:33272","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-18T02:52:56.871777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.095082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:56.872265Z","caller":"traceutil/trace.go:171","msg":"trace[171163989] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:906; }","duration":"967.604442ms","start":"2026-02-18T02:52:55.90465Z","end":"2026-02-18T02:52:56.872255Z","steps":["trace[171163989] 'count revisions from in-memory index tree' (duration: 967.037961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:56.872288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:52:55.904637Z","time spent":"967.642604ms","remote":"127.0.0.1:33478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-18T02:52:59.142148Z","caller":"traceutil/trace.go:171","msg":"trace[1797399357] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"189.799944ms","start":"2026-02-18T02:52:58.952321Z","end":"2026-02-18T02:52:59.142121Z","steps":["trace[1797399357] 'read index received' (duration: 161.102923ms)","trace[1797399357] 'applied index is now lower than readState.Index' (duration: 28.695881ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:52:59.142261Z","caller":"traceutil/trace.go:171","msg":"trace[1531422805] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"203.937623ms","start":"2026-02-18T02:52:58.938305Z","end":"2026-02-18T02:52:59.142242Z","steps":["trace[1531422805] 'process raft request' (duration: 175.159821ms)","trace[1531422805] 'compare' (duration: 28.523897ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:59.142356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.010448ms","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-18T02:52:59.14239Z","caller":"traceutil/trace.go:171","msg":"trace[652522919] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:913; }","duration":"190.071769ms","start":"2026-02-18T02:52:58.952309Z","end":"2026-02-18T02:52:59.142381Z","steps":["trace[652522919] 'agreement among raft nodes before linearized reading' (duration: 189.938416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:59.142574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.611182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T02:52:59.142594Z","caller":"traceutil/trace.go:171","msg":"trace[677734948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:913; }","duration":"170.658934ms","start":"2026-02-18T02:52:58.97193Z","end":"2026-02-18T02:52:59.142589Z","steps":["trace[677734948] 'agreement among raft nodes before linearized reading' (duration: 170.577032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:59.142676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.018719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:52:59.14269Z","caller":"traceutil/trace.go:171","msg":"trace[695613909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:913; }","duration":"170.04089ms","start":"2026-02-18T02:52:58.972645Z","end":"2026-02-18T02:52:59.142686Z","steps":["trace[695613909] 'agreement among raft nodes before linearized reading' (duration: 170.003319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:52:59.142761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.792526ms","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-18T02:52:59.142772Z","caller":"traceutil/trace.go:171","msg":"trace[1957853747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"170.811296ms","start":"2026-02-18T02:52:58.971957Z","end":"2026-02-18T02:52:59.142768Z","steps":["trace[1957853747] 'agreement among raft nodes before linearized reading' (duration: 170.779416ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:59.718516Z","caller":"traceutil/trace.go:171","msg":"trace[2123832933] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"110.379211ms","start":"2026-02-18T02:52:59.608117Z","end":"2026-02-18T02:52:59.718496Z","steps":["trace[2123832933] 'read index received' (duration: 110.040143ms)","trace[2123832933] 'applied index is now lower than readState.Index' (duration: 338.248µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:52:59.718675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.537194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:52:59.718708Z","caller":"traceutil/trace.go:171","msg":"trace[637833726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"110.605045ms","start":"2026-02-18T02:52:59.608093Z","end":"2026-02-18T02:52:59.718698Z","steps":["trace[637833726] 'agreement among raft nodes before linearized reading' (duration: 110.513183ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:52:59.719049Z","caller":"traceutil/trace.go:171","msg":"trace[592228092] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"123.667233ms","start":"2026-02-18T02:52:59.59537Z","end":"2026-02-18T02:52:59.719038Z","steps":["trace[592228092] 'process raft request' (duration: 122.837304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:05.111684Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694841556440047,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:53:05.291026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.406559ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:53:05.308784Z","caller":"traceutil/trace.go:171","msg":"trace[383164559] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"697.308445ms","start":"2026-02-18T02:53:04.611461Z","end":"2026-02-18T02:53:05.30877Z","steps":["trace[383164559] 'read index received' (duration: 697.277294ms)","trace[383164559] 'applied index is now lower than readState.Index' (duration: 30.521µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:53:05.3089Z","caller":"traceutil/trace.go:171","msg":"trace[125665470] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.090285064s","start":"2026-02-18T02:53:04.21861Z","end":"2026-02-18T02:53:05.308895Z","steps":["trace[125665470] 'process raft request' (duration: 1.090024489s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:05.308958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:04.218591Z","time spent":"1.090322685s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:53:05.309082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.862023ms","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-18T02:53:05.309104Z","caller":"traceutil/trace.go:171","msg":"trace[895943699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"137.907845ms","start":"2026-02-18T02:53:05.17119Z","end":"2026-02-18T02:53:05.309098Z","steps":["trace[895943699] 'agreement among raft nodes before linearized reading' (duration: 137.833173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:05.30917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.708863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:53:05.309186Z","caller":"traceutil/trace.go:171","msg":"trace[2133279373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"697.769394ms","start":"2026-02-18T02:53:04.61141Z","end":"2026-02-18T02:53:05.309179Z","steps":["trace[2133279373] 'agreement among raft nodes before linearized reading' (duration: 697.748094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:05.309198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:04.611396Z","time spent":"697.799146ms","remote":"127.0.0.1:33028","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-18T02:53:05.309301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.636519ms","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-18T02:53:05.309315Z","caller":"traceutil/trace.go:171","msg":"trace[947110857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"127.671419ms","start":"2026-02-18T02:53:05.18164Z","end":"2026-02-18T02:53:05.309311Z","steps":["trace[947110857] 'agreement among raft nodes before linearized reading' (duration: 127.632458ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:05.511874Z","caller":"traceutil/trace.go:171","msg":"trace[637144677] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"194.569958ms","start":"2026-02-18T02:53:05.317289Z","end":"2026-02-18T02:53:05.511859Z","steps":["trace[637144677] 'process raft request' (duration: 194.549467ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:05.512153Z","caller":"traceutil/trace.go:171","msg":"trace[1030250990] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"198.212378ms","start":"2026-02-18T02:53:05.313935Z","end":"2026-02-18T02:53:05.512147Z","steps":["trace[1030250990] 'process raft request' (duration: 98.836158ms)","trace[1030250990] 'compare' (duration: 98.920619ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:53:05.512245Z","caller":"traceutil/trace.go:171","msg":"trace[1316675936] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"198.172797ms","start":"2026-02-18T02:53:05.314067Z","end":"2026-02-18T02:53:05.512239Z","steps":["trace[1316675936] 'process raft request' (duration: 197.712536ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:05.51233Z","caller":"traceutil/trace.go:171","msg":"trace[1324931848] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"198.216508ms","start":"2026-02-18T02:53:05.314109Z","end":"2026-02-18T02:53:05.512325Z","steps":["trace[1324931848] 'process raft request' (duration: 197.695025ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:13.734588Z","caller":"traceutil/trace.go:171","msg":"trace[775900781] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"136.78886ms","start":"2026-02-18T02:53:13.59778Z","end":"2026-02-18T02:53:13.734569Z","steps":["trace[775900781] 'read index received' (duration: 136.662097ms)","trace[775900781] 'applied index is now lower than readState.Index' (duration: 126.223µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:53:13.734651Z","caller":"traceutil/trace.go:171","msg":"trace[1414074561] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"143.215821ms","start":"2026-02-18T02:53:13.59143Z","end":"2026-02-18T02:53:13.734646Z","steps":["trace[1414074561] 'process raft request' (duration: 143.053927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:13.734903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.398954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-18T02:53:13.734959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.163658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:53:13.734982Z","caller":"traceutil/trace.go:171","msg":"trace[2114819972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"137.21713ms","start":"2026-02-18T02:53:13.597759Z","end":"2026-02-18T02:53:13.734976Z","steps":["trace[2114819972] 'agreement among raft nodes before linearized reading' (duration: 137.126148ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:13.734976Z","caller":"traceutil/trace.go:171","msg":"trace[161154703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:953; }","duration":"132.486605ms","start":"2026-02-18T02:53:13.602471Z","end":"2026-02-18T02:53:13.734957Z","steps":["trace[161154703] 'agreement among raft nodes before linearized reading' (duration: 132.310292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:13.735102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.864951ms","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-18T02:53:13.735116Z","caller":"traceutil/trace.go:171","msg":"trace[1998719522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"130.888431ms","start":"2026-02-18T02:53:13.604223Z","end":"2026-02-18T02:53:13.735112Z","steps":["trace[1998719522] 'agreement among raft nodes before linearized reading' (duration: 130.85115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:14.34969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.973067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:53:14.350734Z","caller":"traceutil/trace.go:171","msg":"trace[1821922976] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:998; }","duration":"450.340867ms","start":"2026-02-18T02:53:13.900377Z","end":"2026-02-18T02:53:14.350718Z","steps":["trace[1821922976] 'read index received' (duration: 197.285346ms)","trace[1821922976] 'applied index is now lower than readState.Index' (duration: 253.054691ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:53:14.350891Z","caller":"traceutil/trace.go:171","msg":"trace[300657124] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"610.729636ms","start":"2026-02-18T02:53:13.740142Z","end":"2026-02-18T02:53:14.350871Z","steps":["trace[300657124] 'process raft request' (duration: 357.510581ms)","trace[300657124] 'compare' (duration: 251.840664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:53:14.350978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:13.740128Z","time spent":"610.807567ms","remote":"127.0.0.1:33272","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-18T02:53:14.351069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.617473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:53:14.351145Z","caller":"traceutil/trace.go:171","msg":"trace[2029357596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:956; }","duration":"450.793927ms","start":"2026-02-18T02:53:13.90034Z","end":"2026-02-18T02:53:14.351134Z","steps":["trace[2029357596] 'agreement among raft nodes before linearized reading' (duration: 450.494821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:14.35123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:13.900307Z","time spent":"450.911ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T02:53:14.351291Z","caller":"traceutil/trace.go:171","msg":"trace[1838452741] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"608.290802ms","start":"2026-02-18T02:53:13.742993Z","end":"2026-02-18T02:53:14.351284Z","steps":["trace[1838452741] 'process raft request' (duration: 607.691719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:14.351348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:13.742974Z","time spent":"608.345273ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:53:14.351249Z","caller":"traceutil/trace.go:171","msg":"trace[1510617626] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"610.073332ms","start":"2026-02-18T02:53:13.741166Z","end":"2026-02-18T02:53:14.351239Z","steps":["trace[1510617626] 'process raft request' (duration: 609.453708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:14.351532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:13.741158Z","time spent":"610.308277ms","remote":"127.0.0.1:33272","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-18T02:53:14.479694Z","caller":"traceutil/trace.go:171","msg":"trace[1191579950] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"122.755153ms","start":"2026-02-18T02:53:14.356921Z","end":"2026-02-18T02:53:14.479676Z","steps":["trace[1191579950] 'process raft request' (duration: 122.655191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:14.92599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.062904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:53:14.926067Z","caller":"traceutil/trace.go:171","msg":"trace[563532797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"317.226587ms","start":"2026-02-18T02:53:14.608823Z","end":"2026-02-18T02:53:14.926049Z","steps":["trace[563532797] 'range keys from in-memory index tree' (duration: 317.013433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:14.926107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:14.60881Z","time spent":"317.288329ms","remote":"127.0.0.1:33028","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-18T02:53:15.198163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.493574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4783"} {"level":"info","ts":"2026-02-18T02:53:15.198212Z","caller":"traceutil/trace.go:171","msg":"trace[751392678] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:958; }","duration":"105.620006ms","start":"2026-02-18T02:53:15.09258Z","end":"2026-02-18T02:53:15.1982Z","steps":["trace[751392678] 'range keys from in-memory index tree' (duration: 105.378892ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:15.401239Z","caller":"traceutil/trace.go:171","msg":"trace[812417323] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"196.686035ms","start":"2026-02-18T02:53:15.204541Z","end":"2026-02-18T02:53:15.401227Z","steps":["trace[812417323] 'process raft request' (duration: 196.4685ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:18.507383Z","caller":"traceutil/trace.go:171","msg":"trace[1332281849] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"133.792093ms","start":"2026-02-18T02:53:18.373575Z","end":"2026-02-18T02:53:18.507367Z","steps":["trace[1332281849] 'process raft request' (duration: 133.690511ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:18.508014Z","caller":"traceutil/trace.go:171","msg":"trace[1363868547] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"131.016414ms","start":"2026-02-18T02:53:18.376989Z","end":"2026-02-18T02:53:18.508005Z","steps":["trace[1363868547] 'process raft request' (duration: 130.967583ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:18.508246Z","caller":"traceutil/trace.go:171","msg":"trace[1038786697] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"132.026105ms","start":"2026-02-18T02:53:18.376211Z","end":"2026-02-18T02:53:18.508238Z","steps":["trace[1038786697] 'process raft request' (duration: 131.704088ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:53:28.50485Z","caller":"traceutil/trace.go:171","msg":"trace[218393650] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"711.424934ms","start":"2026-02-18T02:53:27.793406Z","end":"2026-02-18T02:53:28.504831Z","steps":["trace[218393650] 'process raft request' (duration: 711.317111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:28.50496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:53:27.793395Z","time spent":"711.509376ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:53:28.762282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.65099ms","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-18T02:53:28.762359Z","caller":"traceutil/trace.go:171","msg":"trace[1095915375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1034; }","duration":"215.768253ms","start":"2026-02-18T02:53:28.546573Z","end":"2026-02-18T02:53:28.762341Z","steps":["trace[1095915375] 'range keys from in-memory index tree' (duration: 215.20542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:28.762654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.547107ms","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-18T02:53:28.762685Z","caller":"traceutil/trace.go:171","msg":"trace[507550385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1034; }","duration":"210.616089ms","start":"2026-02-18T02:53:28.55206Z","end":"2026-02-18T02:53:28.762676Z","steps":["trace[507550385] 'range keys from in-memory index tree' (duration: 210.430875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:28.764352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.869072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:53:28.764426Z","caller":"traceutil/trace.go:171","msg":"trace[884638752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"203.975284ms","start":"2026-02-18T02:53:28.560429Z","end":"2026-02-18T02:53:28.764405Z","steps":["trace[884638752] 'range keys from in-memory index tree' (duration: 203.705418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:28.76499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.213397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:53:28.765032Z","caller":"traceutil/trace.go:171","msg":"trace[1067083748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"187.276108ms","start":"2026-02-18T02:53:28.577744Z","end":"2026-02-18T02:53:28.765021Z","steps":["trace[1067083748] 'range keys from in-memory index tree' (duration: 187.126355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:53:28.76454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.055105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:53:28.770508Z","caller":"traceutil/trace.go:171","msg":"trace[195990929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"158.051547ms","start":"2026-02-18T02:53:28.612437Z","end":"2026-02-18T02:53:28.770489Z","steps":["trace[195990929] 'range keys from in-memory index tree' (duration: 151.966963ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:54:25.304754Z","caller":"traceutil/trace.go:171","msg":"trace[727711136] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"104.185019ms","start":"2026-02-18T02:54:25.200537Z","end":"2026-02-18T02:54:25.304722Z","steps":["trace[727711136] 'read index received' (duration: 61.374522ms)","trace[727711136] 'applied index is now lower than readState.Index' (duration: 42.809387ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:54:25.311695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.147084ms","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-18T02:54:25.311727Z","caller":"traceutil/trace.go:171","msg":"trace[663042979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"111.257737ms","start":"2026-02-18T02:54:25.200457Z","end":"2026-02-18T02:54:25.311715Z","steps":["trace[663042979] 'agreement among raft nodes before linearized reading' (duration: 111.079093ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:54:25.305333Z","caller":"traceutil/trace.go:171","msg":"trace[1776157327] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"151.277592ms","start":"2026-02-18T02:54:25.154036Z","end":"2026-02-18T02:54:25.305314Z","steps":["trace[1776157327] 'process raft request' (duration: 107.932214ms)","trace[1776157327] 'compare' (duration: 42.358207ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:55:09.722456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.57428ms","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-18T02:55:09.722549Z","caller":"traceutil/trace.go:171","msg":"trace[1637399140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1465; }","duration":"212.842186ms","start":"2026-02-18T02:55:09.50969Z","end":"2026-02-18T02:55:09.722532Z","steps":["trace[1637399140] 'range keys from in-memory index tree' (duration: 212.406157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:55:09.722977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.811064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:55:09.726318Z","caller":"traceutil/trace.go:171","msg":"trace[1746014976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1465; }","duration":"125.17335ms","start":"2026-02-18T02:55:09.601128Z","end":"2026-02-18T02:55:09.726301Z","steps":["trace[1746014976] 'range keys from in-memory index tree' (duration: 121.683791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:55:09.727246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.969738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T02:55:09.727294Z","caller":"traceutil/trace.go:171","msg":"trace[1203570717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1465; }","duration":"126.05778ms","start":"2026-02-18T02:55:09.601223Z","end":"2026-02-18T02:55:09.727281Z","steps":["trace[1203570717] 'range keys from in-memory index tree' (duration: 125.793884ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:56:40.17263Z","caller":"traceutil/trace.go:171","msg":"trace[1723676587] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"273.017342ms","start":"2026-02-18T02:56:39.899521Z","end":"2026-02-18T02:56:40.172538Z","steps":["trace[1723676587] 'process raft request' (duration: 272.882419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:56:40.360437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.750463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:56:40.360707Z","caller":"traceutil/trace.go:171","msg":"trace[1023234186] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"182.282454ms","start":"2026-02-18T02:56:40.178409Z","end":"2026-02-18T02:56:40.360692Z","steps":["trace[1023234186] 'process raft request' (duration: 38.224393ms)","trace[1023234186] 'compare' (duration: 143.653051ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:56:45.859455Z","caller":"traceutil/trace.go:171","msg":"trace[2101251655] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1975; }","duration":"216.985357ms","start":"2026-02-18T02:56:45.642448Z","end":"2026-02-18T02:56:45.859433Z","steps":["trace[2101251655] 'read index received' (duration: 216.803863ms)","trace[2101251655] 'applied index is now lower than readState.Index' (duration: 180.494µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:56:45.85973Z","caller":"traceutil/trace.go:171","msg":"trace[79680839] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"229.770442ms","start":"2026-02-18T02:56:45.629949Z","end":"2026-02-18T02:56:45.859719Z","steps":["trace[79680839] 'process raft request' (duration: 229.355842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:56:45.859993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.52934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-18T02:56:45.860036Z","caller":"traceutil/trace.go:171","msg":"trace[1974541649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1887; }","duration":"217.609071ms","start":"2026-02-18T02:56:45.642418Z","end":"2026-02-18T02:56:45.860027Z","steps":["trace[1974541649] 'agreement among raft nodes before linearized reading' (duration: 217.478648ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:56:46.779578Z","caller":"traceutil/trace.go:171","msg":"trace[2086496885] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1980; }","duration":"298.717967ms","start":"2026-02-18T02:56:46.480847Z","end":"2026-02-18T02:56:46.779565Z","steps":["trace[2086496885] 'read index received' (duration: 292.671217ms)","trace[2086496885] 'applied index is now lower than readState.Index' (duration: 6.04615ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:56:46.779724Z","caller":"traceutil/trace.go:171","msg":"trace[912717149] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"300.503057ms","start":"2026-02-18T02:56:46.479214Z","end":"2026-02-18T02:56:46.779717Z","steps":["trace[912717149] 'process raft request' (duration: 294.340345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:56:46.779784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:56:46.479197Z","time spent":"300.542318ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:56:46.779918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.077655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-18T02:56:46.779935Z","caller":"traceutil/trace.go:171","msg":"trace[436952415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1892; }","duration":"299.110425ms","start":"2026-02-18T02:56:46.480818Z","end":"2026-02-18T02:56:46.779929Z","steps":["trace[436952415] 'agreement among raft nodes before linearized reading' (duration: 299.044574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:56:46.779998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.755035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:56:46.78001Z","caller":"traceutil/trace.go:171","msg":"trace[1335595913] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1892; }","duration":"217.829726ms","start":"2026-02-18T02:56:46.562176Z","end":"2026-02-18T02:56:46.780006Z","steps":["trace[1335595913] 'agreement among raft nodes before linearized reading' (duration: 217.811386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:56:46.780115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.193801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:56:46.780127Z","caller":"traceutil/trace.go:171","msg":"trace[1437281787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1892; }","duration":"169.219862ms","start":"2026-02-18T02:56:46.610903Z","end":"2026-02-18T02:56:46.780123Z","steps":["trace[1437281787] 'agreement among raft nodes before linearized reading' (duration: 169.186601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:56:46.780212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.960417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-18T02:56:46.780224Z","caller":"traceutil/trace.go:171","msg":"trace[418643382] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1892; }","duration":"201.020738ms","start":"2026-02-18T02:56:46.579199Z","end":"2026-02-18T02:56:46.78022Z","steps":["trace[418643382] 'agreement among raft nodes before linearized reading' (duration: 200.983617ms)"],"step_count":1}