{"level":"warn","ts":"2026-02-25T06:10:03.164278Z","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-25T06:10:03.164388Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-25T06:10:03.164468Z","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-25T06:10:03.164484Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-25T06:10:03.164504Z","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-25T06:10:03.165352Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-02-25T06:10:03.16549Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-25T06:10:03.172647Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.860953ms"} {"level":"info","ts":"2026-02-25T06:10:03.179022Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-02-25T06:10:03.179129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T06:10:03.179154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-02-25T06:10:03.179195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T06:10:03.179213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-02-25T06:10:03.180955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-02-25T06:10:03.19427Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T06:10:03.196239Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T06:10:03.197789Z","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-25T06:10:03.20006Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T06:10:03.20047Z","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-25T06:10:03.200614Z","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-25T06:10:03.200704Z","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-25T06:10:03.200521Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T06:10:03.202985Z","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-25T06:10:03.203183Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T06:10:03.203222Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T06:10:03.203251Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-25T06:10:03.203273Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-25T06:10:03.204202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-02-25T06:10:03.204343Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-25T06:10:03.982361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T06:10:03.982461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T06:10:03.982494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-02-25T06:10:03.982524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-02-25T06:10:03.982542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-25T06:10:03.982578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-02-25T06:10:03.9826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-25T06:10:03.98389Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T06:10:03.983927Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:10:03.984041Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:10:03.984079Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:03.984178Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T06:10:03.984221Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T06:10:03.985033Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:03.988069Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:03.98829Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:03.989945Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T06:10:03.998185Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-02-25T06:10:05.530207Z","caller":"traceutil/trace.go:171","msg":"trace[1989687555] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"176.814474ms","start":"2026-02-25T06:10:05.353369Z","end":"2026-02-25T06:10:05.530184Z","steps":["trace[1989687555] 'process raft request' (duration: 176.594267ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.586575Z","caller":"traceutil/trace.go:171","msg":"trace[648248571] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"154.203692ms","start":"2026-02-25T06:10:05.432333Z","end":"2026-02-25T06:10:05.586537Z","steps":["trace[648248571] 'process raft request' (duration: 153.978135ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.586661Z","caller":"traceutil/trace.go:171","msg":"trace[1418617040] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"154.135141ms","start":"2026-02-25T06:10:05.43248Z","end":"2026-02-25T06:10:05.586615Z","steps":["trace[1418617040] 'process raft request' (duration: 153.974855ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.586928Z","caller":"traceutil/trace.go:171","msg":"trace[1195526512] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"233.396966ms","start":"2026-02-25T06:10:05.353516Z","end":"2026-02-25T06:10:05.586913Z","steps":["trace[1195526512] 'process raft request' (duration: 232.617541ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.587109Z","caller":"traceutil/trace.go:171","msg":"trace[1214270829] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"154.670648ms","start":"2026-02-25T06:10:05.432358Z","end":"2026-02-25T06:10:05.587028Z","steps":["trace[1214270829] 'process raft request' (duration: 154.014337ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.587185Z","caller":"traceutil/trace.go:171","msg":"trace[1303104972] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"223.255638ms","start":"2026-02-25T06:10:05.363878Z","end":"2026-02-25T06:10:05.587133Z","steps":["trace[1303104972] 'read index received' (duration: 166.039956ms)","trace[1303104972] 'applied index is now lower than readState.Index' (duration: 57.212132ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:05.587423Z","caller":"traceutil/trace.go:171","msg":"trace[751310379] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"154.870684ms","start":"2026-02-25T06:10:05.432457Z","end":"2026-02-25T06:10:05.587327Z","steps":["trace[751310379] 'process raft request' (duration: 153.943054ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.587475Z","caller":"traceutil/trace.go:171","msg":"trace[1787473158] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"139.01292ms","start":"2026-02-25T06:10:05.448424Z","end":"2026-02-25T06:10:05.587437Z","steps":["trace[1787473158] 'process raft request' (duration: 138.665289ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.58726Z","caller":"traceutil/trace.go:171","msg":"trace[1099869859] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"145.342335ms","start":"2026-02-25T06:10:05.441849Z","end":"2026-02-25T06:10:05.587191Z","steps":["trace[1099869859] 'process raft request' (duration: 144.736436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:05.587736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.834737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T06:10:05.587782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.816277ms","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-25T06:10:05.587843Z","caller":"traceutil/trace.go:171","msg":"trace[461001005] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"223.988612ms","start":"2026-02-25T06:10:05.36384Z","end":"2026-02-25T06:10:05.587828Z","steps":["trace[461001005] 'agreement among raft nodes before linearized reading' (duration: 223.63485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:05.587826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.481643ms","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-25T06:10:05.587867Z","caller":"traceutil/trace.go:171","msg":"trace[1669089003] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"156.94192ms","start":"2026-02-25T06:10:05.430905Z","end":"2026-02-25T06:10:05.587846Z","steps":["trace[1669089003] 'agreement among raft nodes before linearized reading' (duration: 156.648621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:05.587923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.156177ms","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-25T06:10:05.58796Z","caller":"traceutil/trace.go:171","msg":"trace[1097785511] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"186.222929ms","start":"2026-02-25T06:10:05.401723Z","end":"2026-02-25T06:10:05.587946Z","steps":["trace[1097785511] 'agreement among raft nodes before linearized reading' (duration: 186.183607ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.587955Z","caller":"traceutil/trace.go:171","msg":"trace[422636027] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"104.603967ms","start":"2026-02-25T06:10:05.483279Z","end":"2026-02-25T06:10:05.587883Z","steps":["trace[422636027] 'agreement among raft nodes before linearized reading' (duration: 104.428481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:05.587779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.147267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T06:10:05.587891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.169215ms","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-25T06:10:05.588096Z","caller":"traceutil/trace.go:171","msg":"trace[2056730675] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"186.531119ms","start":"2026-02-25T06:10:05.401557Z","end":"2026-02-25T06:10:05.588088Z","steps":["trace[2056730675] 'agreement among raft nodes before linearized reading' (duration: 185.969871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:05.587853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.497571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T06:10:05.587217Z","caller":"traceutil/trace.go:171","msg":"trace[665430319] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"154.44874ms","start":"2026-02-25T06:10:05.432703Z","end":"2026-02-25T06:10:05.587152Z","steps":["trace[665430319] 'process raft request' (duration: 153.84289ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.588134Z","caller":"traceutil/trace.go:171","msg":"trace[813458120] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"185.416433ms","start":"2026-02-25T06:10:05.402697Z","end":"2026-02-25T06:10:05.588114Z","steps":["trace[813458120] 'agreement among raft nodes before linearized reading' (duration: 185.177135ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.588165Z","caller":"traceutil/trace.go:171","msg":"trace[1365672183] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:0; response_revision:11; }","duration":"158.825161ms","start":"2026-02-25T06:10:05.429327Z","end":"2026-02-25T06:10:05.588153Z","steps":["trace[1365672183] 'agreement among raft nodes before linearized reading' (duration: 158.495081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:05.588379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.098141ms","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-25T06:10:05.58724Z","caller":"traceutil/trace.go:171","msg":"trace[829704506] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"154.570144ms","start":"2026-02-25T06:10:05.432615Z","end":"2026-02-25T06:10:05.587186Z","steps":["trace[829704506] 'process raft request' (duration: 153.867271ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.588643Z","caller":"traceutil/trace.go:171","msg":"trace[1751277795] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"155.394001ms","start":"2026-02-25T06:10:05.43323Z","end":"2026-02-25T06:10:05.588624Z","steps":["trace[1751277795] 'agreement among raft nodes before linearized reading' (duration: 155.07216ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:26.37791Z","caller":"traceutil/trace.go:171","msg":"trace[1177936167] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"230.078959ms","start":"2026-02-25T06:10:26.147782Z","end":"2026-02-25T06:10:26.377861Z","steps":["trace[1177936167] 'process raft request' (duration: 229.859532ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:35.991902Z","caller":"traceutil/trace.go:171","msg":"trace[1060063116] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"170.254092ms","start":"2026-02-25T06:10:35.821619Z","end":"2026-02-25T06:10:35.991873Z","steps":["trace[1060063116] 'read index received' (duration: 170.051525ms)","trace[1060063116] 'applied index is now lower than readState.Index' (duration: 201.507µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:35.991984Z","caller":"traceutil/trace.go:171","msg":"trace[32186778] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"189.730302ms","start":"2026-02-25T06:10:35.802211Z","end":"2026-02-25T06:10:35.991942Z","steps":["trace[32186778] 'process raft request' (duration: 189.489264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:35.992062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.425067ms","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-25T06:10:35.992104Z","caller":"traceutil/trace.go:171","msg":"trace[25988148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"170.51262ms","start":"2026-02-25T06:10:35.821579Z","end":"2026-02-25T06:10:35.992091Z","steps":["trace[25988148] 'agreement among raft nodes before linearized reading' (duration: 170.409486ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:42.284776Z","caller":"traceutil/trace.go:171","msg":"trace[1483886904] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"267.78879ms","start":"2026-02-25T06:10:42.016957Z","end":"2026-02-25T06:10:42.284746Z","steps":["trace[1483886904] 'read index received' (duration: 267.624405ms)","trace[1483886904] 'applied index is now lower than readState.Index' (duration: 163.055µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:42.28497Z","caller":"traceutil/trace.go:171","msg":"trace[1528164184] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"333.546019ms","start":"2026-02-25T06:10:41.951412Z","end":"2026-02-25T06:10:42.284958Z","steps":["trace[1528164184] 'process raft request' (duration: 333.159766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.285474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.496183ms","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-25T06:10:42.285598Z","caller":"traceutil/trace.go:171","msg":"trace[222414066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"268.69524ms","start":"2026-02-25T06:10:42.016872Z","end":"2026-02-25T06:10:42.285567Z","steps":["trace[222414066] 'agreement among raft nodes before linearized reading' (duration: 268.314657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.28573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:41.951374Z","time spent":"333.625471ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:42.355945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.153073ms","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-25T06:10:42.356052Z","caller":"traceutil/trace.go:171","msg":"trace[1200034358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"294.322069ms","start":"2026-02-25T06:10:42.061705Z","end":"2026-02-25T06:10:42.356027Z","steps":["trace[1200034358] 'agreement among raft nodes before linearized reading' (duration: 293.933426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.962327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218092340457817,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:10:42.981951Z","caller":"traceutil/trace.go:171","msg":"trace[1622701708] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"520.057817ms","start":"2026-02-25T06:10:42.461869Z","end":"2026-02-25T06:10:42.981926Z","steps":["trace[1622701708] 'read index received' (duration: 519.907182ms)","trace[1622701708] 'applied index is now lower than readState.Index' (duration: 148.975µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:42.982085Z","caller":"traceutil/trace.go:171","msg":"trace[909834769] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"622.014509ms","start":"2026-02-25T06:10:42.360056Z","end":"2026-02-25T06:10:42.982071Z","steps":["trace[909834769] 'process raft request' (duration: 621.714189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.982212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:42.360017Z","time spent":"622.107062ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:42.982443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.016478ms","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-25T06:10:42.982557Z","caller":"traceutil/trace.go:171","msg":"trace[595743456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"504.228945ms","start":"2026-02-25T06:10:42.478301Z","end":"2026-02-25T06:10:42.98253Z","steps":["trace[595743456] 'agreement among raft nodes before linearized reading' (duration: 503.974317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.98257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.168645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:42.982612Z","caller":"traceutil/trace.go:171","msg":"trace[943414319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"450.233877ms","start":"2026-02-25T06:10:42.532365Z","end":"2026-02-25T06:10:42.982599Z","steps":["trace[943414319] 'agreement among raft nodes before linearized reading' (duration: 450.170385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.982615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:42.478291Z","time spent":"504.312538ms","remote":"127.0.0.1:48170","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-25T06:10:42.982643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:42.53235Z","time spent":"450.287899ms","remote":"127.0.0.1:47862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:10:42.982712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.802431ms","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-25T06:10:42.982812Z","caller":"traceutil/trace.go:171","msg":"trace[963447832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:497; }","duration":"520.975407ms","start":"2026-02-25T06:10:42.461813Z","end":"2026-02-25T06:10:42.982788Z","steps":["trace[963447832] 'agreement among raft nodes before linearized reading' (duration: 520.649837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.982869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:42.461789Z","time spent":"521.06896ms","remote":"127.0.0.1:48170","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":"info","ts":"2026-02-25T06:10:44.717986Z","caller":"traceutil/trace.go:171","msg":"trace[2028528591] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"186.638352ms","start":"2026-02-25T06:10:44.531314Z","end":"2026-02-25T06:10:44.717953Z","steps":["trace[2028528591] 'read index received' (duration: 186.339273ms)","trace[2028528591] 'applied index is now lower than readState.Index' (duration: 297.499µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:10:44.718133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.800398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:44.718126Z","caller":"traceutil/trace.go:171","msg":"trace[1914463640] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"351.657575ms","start":"2026-02-25T06:10:44.366425Z","end":"2026-02-25T06:10:44.718082Z","steps":["trace[1914463640] 'process raft request' (duration: 351.326415ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:44.718171Z","caller":"traceutil/trace.go:171","msg":"trace[1588468023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"186.905071ms","start":"2026-02-25T06:10:44.531253Z","end":"2026-02-25T06:10:44.718158Z","steps":["trace[1588468023] 'agreement among raft nodes before linearized reading' (duration: 186.819538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:44.718665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:44.366394Z","time spent":"351.849262ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:10:56.935146Z","caller":"traceutil/trace.go:171","msg":"trace[255276187] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"164.2986ms","start":"2026-02-25T06:10:56.770819Z","end":"2026-02-25T06:10:56.935118Z","steps":["trace[255276187] 'read index received' (duration: 164.127264ms)","trace[255276187] 'applied index is now lower than readState.Index' (duration: 170.146µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:56.935473Z","caller":"traceutil/trace.go:171","msg":"trace[597390319] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"267.872183ms","start":"2026-02-25T06:10:56.667589Z","end":"2026-02-25T06:10:56.935461Z","steps":["trace[597390319] 'process raft request' (duration: 267.260413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:56.935877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.042653ms","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-25T06:10:56.935926Z","caller":"traceutil/trace.go:171","msg":"trace[926693315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:658; }","duration":"165.153678ms","start":"2026-02-25T06:10:56.770759Z","end":"2026-02-25T06:10:56.935913Z","steps":["trace[926693315] 'agreement among raft nodes before linearized reading' (duration: 164.995162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:03.909105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.992851ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T06:11:03.909263Z","caller":"traceutil/trace.go:171","msg":"trace[80741079] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"1.378782309s","start":"2026-02-25T06:11:02.530443Z","end":"2026-02-25T06:11:03.909226Z","steps":["trace[80741079] 'read index received' (duration: 426.433866ms)","trace[80741079] 'applied index is now lower than readState.Index' (duration: 952.346573ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:03.90969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379188173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:03.910242Z","caller":"traceutil/trace.go:171","msg":"trace[1283141620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"1.379806952s","start":"2026-02-25T06:11:02.530387Z","end":"2026-02-25T06:11:03.910194Z","steps":["trace[1283141620] 'agreement among raft nodes before linearized reading' (duration: 1.379158951s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:03.910391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:02.530356Z","time spent":"1.380013489s","remote":"127.0.0.1:47862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:11:03.90982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.532193ms","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-25T06:11:03.910638Z","caller":"traceutil/trace.go:171","msg":"trace[44906508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:684; }","duration":"879.370561ms","start":"2026-02-25T06:11:03.031238Z","end":"2026-02-25T06:11:03.910596Z","steps":["trace[44906508] 'agreement among raft nodes before linearized reading' (duration: 878.444441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:03.909818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.097768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T06:11:03.910688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:03.031223Z","time spent":"879.453203ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T06:11:03.910733Z","caller":"traceutil/trace.go:171","msg":"trace[1545354542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"748.05995ms","start":"2026-02-25T06:11:03.162642Z","end":"2026-02-25T06:11:03.910702Z","steps":["trace[1545354542] 'agreement among raft nodes before linearized reading' (duration: 747.002985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:03.909868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.369218ms","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-25T06:11:03.910802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:03.162586Z","time spent":"748.198964ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T06:11:03.910839Z","caller":"traceutil/trace.go:171","msg":"trace[1897090207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:684; }","duration":"786.441392ms","start":"2026-02-25T06:11:03.12437Z","end":"2026-02-25T06:11:03.910812Z","steps":["trace[1897090207] 'agreement among raft nodes before linearized reading' (duration: 785.294625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:03.909981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.234291ms","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-25T06:11:03.910902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:03.124342Z","time spent":"786.544416ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T06:11:03.91091Z","caller":"traceutil/trace.go:171","msg":"trace[1816329218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:684; }","duration":"882.205662ms","start":"2026-02-25T06:11:03.028687Z","end":"2026-02-25T06:11:03.910893Z","steps":["trace[1816329218] 'agreement among raft nodes before linearized reading' (duration: 880.955901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:03.910952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:03.028654Z","time spent":"882.285884ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:03.90994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.609763ms","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-25T06:11:03.911014Z","caller":"traceutil/trace.go:171","msg":"trace[1923169665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:684; }","duration":"743.716869ms","start":"2026-02-25T06:11:03.167287Z","end":"2026-02-25T06:11:03.911003Z","steps":["trace[1923169665] 'agreement among raft nodes before linearized reading' (duration: 742.429037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:03.911053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:03.16725Z","time spent":"743.793362ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:11:06.139412Z","caller":"traceutil/trace.go:171","msg":"trace[928950212] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"127.803775ms","start":"2026-02-25T06:11:06.011568Z","end":"2026-02-25T06:11:06.139372Z","steps":["trace[928950212] 'process raft request' (duration: 127.65724ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:06.139384Z","caller":"traceutil/trace.go:171","msg":"trace[1927909568] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"127.379992ms","start":"2026-02-25T06:11:06.011979Z","end":"2026-02-25T06:11:06.139359Z","steps":["trace[1927909568] 'read index received' (duration: 127.33279ms)","trace[1927909568] 'applied index is now lower than readState.Index' (duration: 45.741µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:06.139746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.6602ms","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-25T06:11:06.139956Z","caller":"traceutil/trace.go:171","msg":"trace[357878319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"128.018752ms","start":"2026-02-25T06:11:06.011922Z","end":"2026-02-25T06:11:06.139941Z","steps":["trace[357878319] 'agreement among raft nodes before linearized reading' (duration: 127.495965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:06.139824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.682801ms","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-25T06:11:06.140172Z","caller":"traceutil/trace.go:171","msg":"trace[1791954781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"128.223899ms","start":"2026-02-25T06:11:06.011918Z","end":"2026-02-25T06:11:06.140142Z","steps":["trace[1791954781] 'agreement among raft nodes before linearized reading' (duration: 127.601889ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:06.303833Z","caller":"traceutil/trace.go:171","msg":"trace[1579513111] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"163.903785ms","start":"2026-02-25T06:11:06.139896Z","end":"2026-02-25T06:11:06.3038Z","steps":["trace[1579513111] 'read index received' (duration: 163.600365ms)","trace[1579513111] 'applied index is now lower than readState.Index' (duration: 302.2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:06.304549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.481965ms","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-25T06:11:06.304607Z","caller":"traceutil/trace.go:171","msg":"trace[539841727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:691; }","duration":"292.588248ms","start":"2026-02-25T06:11:06.012006Z","end":"2026-02-25T06:11:06.304594Z","steps":["trace[539841727] 'agreement among raft nodes before linearized reading' (duration: 291.927067ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:06.304711Z","caller":"traceutil/trace.go:171","msg":"trace[117482337] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"289.798399ms","start":"2026-02-25T06:11:06.014877Z","end":"2026-02-25T06:11:06.304675Z","steps":["trace[117482337] 'process raft request' (duration: 288.710105ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:06.327416Z","caller":"traceutil/trace.go:171","msg":"trace[655778471] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"183.19496ms","start":"2026-02-25T06:11:06.144205Z","end":"2026-02-25T06:11:06.3274Z","steps":["trace[655778471] 'process raft request' (duration: 183.001644ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:06.327406Z","caller":"traceutil/trace.go:171","msg":"trace[948091172] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"181.921989ms","start":"2026-02-25T06:11:06.145457Z","end":"2026-02-25T06:11:06.327379Z","steps":["trace[948091172] 'process raft request' (duration: 181.875088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:10.272891Z","caller":"traceutil/trace.go:171","msg":"trace[346286688] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"120.009877ms","start":"2026-02-25T06:11:10.152849Z","end":"2026-02-25T06:11:10.272859Z","steps":["trace[346286688] 'read index received' (duration: 119.79489ms)","trace[346286688] 'applied index is now lower than readState.Index' (duration: 213.237µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:10.272992Z","caller":"traceutil/trace.go:171","msg":"trace[1641990847] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"120.959517ms","start":"2026-02-25T06:11:10.151998Z","end":"2026-02-25T06:11:10.272958Z","steps":["trace[1641990847] 'process raft request' (duration: 120.667697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:10.273199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.321616ms","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-25T06:11:10.273239Z","caller":"traceutil/trace.go:171","msg":"trace[608168495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:707; }","duration":"120.392288ms","start":"2026-02-25T06:11:10.152835Z","end":"2026-02-25T06:11:10.273227Z","steps":["trace[608168495] 'agreement among raft nodes before linearized reading' (duration: 120.200432ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:30.816616Z","caller":"traceutil/trace.go:171","msg":"trace[159561824] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"384.076814ms","start":"2026-02-25T06:11:30.432508Z","end":"2026-02-25T06:11:30.816585Z","steps":["trace[159561824] 'read index received' (duration: 383.816486ms)","trace[159561824] 'applied index is now lower than readState.Index' (duration: 259.198µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:30.816975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.587311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:30.817036Z","caller":"traceutil/trace.go:171","msg":"trace[1380932944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"283.712235ms","start":"2026-02-25T06:11:30.533307Z","end":"2026-02-25T06:11:30.81702Z","steps":["trace[1380932944] 'agreement among raft nodes before linearized reading' (duration: 283.594921ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:30.817116Z","caller":"traceutil/trace.go:171","msg":"trace[158187126] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"392.933897ms","start":"2026-02-25T06:11:30.424019Z","end":"2026-02-25T06:11:30.816953Z","steps":["trace[158187126] 'process raft request' (duration: 392.38905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:30.817272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.716235ms","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-25T06:11:30.81742Z","caller":"traceutil/trace.go:171","msg":"trace[455975639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"384.948022ms","start":"2026-02-25T06:11:30.432445Z","end":"2026-02-25T06:11:30.817393Z","steps":["trace[455975639] 'agreement among raft nodes before linearized reading' (duration: 384.421675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:30.817508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:30.432422Z","time spent":"385.070536ms","remote":"127.0.0.1:48170","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-25T06:11:30.817546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.429536ms","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-25T06:11:30.817586Z","caller":"traceutil/trace.go:171","msg":"trace[444405253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"182.522909ms","start":"2026-02-25T06:11:30.635053Z","end":"2026-02-25T06:11:30.817576Z","steps":["trace[444405253] 'agreement among raft nodes before linearized reading' (duration: 182.378184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:30.817565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.262801ms","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-25T06:11:30.817682Z","caller":"traceutil/trace.go:171","msg":"trace[1854021435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"209.436856ms","start":"2026-02-25T06:11:30.608217Z","end":"2026-02-25T06:11:30.817654Z","steps":["trace[1854021435] 'agreement among raft nodes before linearized reading' (duration: 209.086395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:30.817513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:30.424003Z","time spent":"393.228396ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:30.818048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.047075ms","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-25T06:11:30.818269Z","caller":"traceutil/trace.go:171","msg":"trace[903720958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"183.224922ms","start":"2026-02-25T06:11:30.634908Z","end":"2026-02-25T06:11:30.818133Z","steps":["trace[903720958] 'agreement among raft nodes before linearized reading' (duration: 182.351704ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:31.12879Z","caller":"traceutil/trace.go:171","msg":"trace[2006006372] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"303.130173ms","start":"2026-02-25T06:11:30.82563Z","end":"2026-02-25T06:11:31.128761Z","steps":["trace[2006006372] 'process raft request' (duration: 303.076202ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:31.128906Z","caller":"traceutil/trace.go:171","msg":"trace[1305625145] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"304.500607ms","start":"2026-02-25T06:11:30.824357Z","end":"2026-02-25T06:11:31.128858Z","steps":["trace[1305625145] 'process raft request' (duration: 304.297131ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:31.128842Z","caller":"traceutil/trace.go:171","msg":"trace[1473638968] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"304.477996ms","start":"2026-02-25T06:11:30.824283Z","end":"2026-02-25T06:11:31.128762Z","steps":["trace[1473638968] 'process raft request' (duration: 304.28079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:31.129251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:30.824273Z","time spent":"304.863939ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:31.129247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:30.825614Z","time spent":"303.293989ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:31.129579Z","caller":"traceutil/trace.go:171","msg":"trace[414696313] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"304.878329ms","start":"2026-02-25T06:11:30.823837Z","end":"2026-02-25T06:11:31.128715Z","steps":["trace[414696313] 'process raft request' (duration: 279.890273ms)","trace[414696313] 'compare' (duration: 24.569113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:31.129617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:30.824328Z","time spent":"304.762106ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:31.129755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:30.823825Z","time spent":"305.8439ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:32.683378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.242252ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T06:11:32.683591Z","caller":"traceutil/trace.go:171","msg":"trace[1108730433] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"148.98949ms","start":"2026-02-25T06:11:32.53458Z","end":"2026-02-25T06:11:32.683569Z","steps":["trace[1108730433] 'read index received' (duration: 48.682µs)","trace[1108730433] 'applied index is now lower than readState.Index' (duration: 148.938518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:32.683725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.136204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:32.683816Z","caller":"traceutil/trace.go:171","msg":"trace[221072583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"149.29341ms","start":"2026-02-25T06:11:32.534508Z","end":"2026-02-25T06:11:32.683801Z","steps":["trace[221072583] 'agreement among raft nodes before linearized reading' (duration: 149.155525ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:33.187419Z","caller":"traceutil/trace.go:171","msg":"trace[1511119106] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"266.31354ms","start":"2026-02-25T06:11:32.921079Z","end":"2026-02-25T06:11:33.187393Z","steps":["trace[1511119106] 'read index received' (duration: 266.110393ms)","trace[1511119106] 'applied index is now lower than readState.Index' (duration: 201.437µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:33.187541Z","caller":"traceutil/trace.go:171","msg":"trace[1598203633] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"357.437184ms","start":"2026-02-25T06:11:32.830064Z","end":"2026-02-25T06:11:33.187501Z","steps":["trace[1598203633] 'process raft request' (duration: 357.140145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:33.187691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.597769ms","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-25T06:11:33.187739Z","caller":"traceutil/trace.go:171","msg":"trace[1419558611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:770; }","duration":"266.719682ms","start":"2026-02-25T06:11:32.921007Z","end":"2026-02-25T06:11:33.187727Z","steps":["trace[1419558611] 'agreement among raft nodes before linearized reading' (duration: 266.550167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:33.188542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:32.830047Z","time spent":"357.584419ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:33.353765Z","caller":"traceutil/trace.go:171","msg":"trace[802008929] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"160.91535ms","start":"2026-02-25T06:11:33.192814Z","end":"2026-02-25T06:11:33.353729Z","steps":["trace[802008929] 'process raft request' (duration: 160.723304ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:33.381662Z","caller":"traceutil/trace.go:171","msg":"trace[40282014] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"188.711236ms","start":"2026-02-25T06:11:33.192928Z","end":"2026-02-25T06:11:33.38164Z","steps":["trace[40282014] 'process raft request' (duration: 188.404006ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:33.381724Z","caller":"traceutil/trace.go:171","msg":"trace[1991259200] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"186.026831ms","start":"2026-02-25T06:11:33.19566Z","end":"2026-02-25T06:11:33.381686Z","steps":["trace[1991259200] 'process raft request' (duration: 185.941598ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:33.382041Z","caller":"traceutil/trace.go:171","msg":"trace[958150919] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"186.496565ms","start":"2026-02-25T06:11:33.195529Z","end":"2026-02-25T06:11:33.382025Z","steps":["trace[958150919] 'process raft request' (duration: 186.02891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:45.59207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.191905ms","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-25T06:11:45.592175Z","caller":"traceutil/trace.go:171","msg":"trace[1851871238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:855; }","duration":"148.37499ms","start":"2026-02-25T06:11:45.443776Z","end":"2026-02-25T06:11:45.592151Z","steps":["trace[1851871238] 'range keys from in-memory index tree' (duration: 147.970337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:45.592462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.95726ms","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-25T06:11:45.592562Z","caller":"traceutil/trace.go:171","msg":"trace[1551957986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:855; }","duration":"144.099254ms","start":"2026-02-25T06:11:45.44844Z","end":"2026-02-25T06:11:45.592539Z","steps":["trace[1551957986] 'range keys from in-memory index tree' (duration: 143.776904ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:45.873388Z","caller":"traceutil/trace.go:171","msg":"trace[1411204407] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"276.700421ms","start":"2026-02-25T06:11:45.596653Z","end":"2026-02-25T06:11:45.873353Z","steps":["trace[1411204407] 'process raft request' (duration: 276.506715ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:46.055152Z","caller":"traceutil/trace.go:171","msg":"trace[1499523191] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"458.237919ms","start":"2026-02-25T06:11:45.59688Z","end":"2026-02-25T06:11:46.055118Z","steps":["trace[1499523191] 'process raft request' (duration: 457.978981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:46.055344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:45.596871Z","time spent":"458.372753ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:46.055611Z","caller":"traceutil/trace.go:171","msg":"trace[125152252] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"458.217008ms","start":"2026-02-25T06:11:45.59738Z","end":"2026-02-25T06:11:46.055597Z","steps":["trace[125152252] 'process raft request' (duration: 457.664021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:46.055681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:45.597368Z","time spent":"458.27602ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:46.055873Z","caller":"traceutil/trace.go:171","msg":"trace[1743273784] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"458.372423ms","start":"2026-02-25T06:11:45.59749Z","end":"2026-02-25T06:11:46.055863Z","steps":["trace[1743273784] 'process raft request' (duration: 458.058503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:46.055935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:45.597484Z","time spent":"458.421755ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:48.033297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218092340458618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:11:48.378517Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.055004633s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:11:48.379012Z","caller":"traceutil/trace.go:171","msg":"trace[1413953252] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:899; }","duration":"846.219158ms","start":"2026-02-25T06:11:47.532757Z","end":"2026-02-25T06:11:48.378976Z","steps":["trace[1413953252] 'read index received' (duration: 845.998641ms)","trace[1413953252] 'applied index is now lower than readState.Index' (duration: 218.937µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:48.379072Z","caller":"traceutil/trace.go:171","msg":"trace[801018068] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"1.055630003s","start":"2026-02-25T06:11:47.323402Z","end":"2026-02-25T06:11:48.379032Z","steps":["trace[801018068] 'process raft request' (duration: 1.055379415s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:48.379283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:47.323382Z","time spent":"1.055780308s","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:48.379255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.467276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:48.379375Z","caller":"traceutil/trace.go:171","msg":"trace[194681523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"846.649731ms","start":"2026-02-25T06:11:47.5327Z","end":"2026-02-25T06:11:48.37935Z","steps":["trace[194681523] 'agreement among raft nodes before linearized reading' (duration: 846.424994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:48.379425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:47.532678Z","time spent":"846.737964ms","remote":"127.0.0.1:47862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T06:11:48.382142Z","caller":"traceutil/trace.go:171","msg":"trace[1665018008] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"109.686817ms","start":"2026-02-25T06:11:48.272431Z","end":"2026-02-25T06:11:48.382117Z","steps":["trace[1665018008] 'process raft request' (duration: 109.307574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:48.382332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.082911ms","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-25T06:11:48.382414Z","caller":"traceutil/trace.go:171","msg":"trace[2051960730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:862; }","duration":"504.172334ms","start":"2026-02-25T06:11:47.878195Z","end":"2026-02-25T06:11:48.382367Z","steps":["trace[2051960730] 'agreement among raft nodes before linearized reading' (duration: 503.981517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:48.382436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.08658ms","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-25T06:11:48.382489Z","caller":"traceutil/trace.go:171","msg":"trace[1253177220] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:862; }","duration":"764.195913ms","start":"2026-02-25T06:11:47.618276Z","end":"2026-02-25T06:11:48.382472Z","steps":["trace[1253177220] 'agreement among raft nodes before linearized reading' (duration: 764.068989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:48.382526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:47.618256Z","time spent":"764.260405ms","remote":"127.0.0.1:48034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:11:48.382533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.401157ms","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-25T06:11:48.38257Z","caller":"traceutil/trace.go:171","msg":"trace[1162374532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:862; }","duration":"321.48593ms","start":"2026-02-25T06:11:48.06107Z","end":"2026-02-25T06:11:48.382556Z","steps":["trace[1162374532] 'agreement among raft nodes before linearized reading' (duration: 321.399947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:48.382596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:48.061035Z","time spent":"321.555392ms","remote":"127.0.0.1:48170","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-25T06:11:48.382836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.325886ms","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-25T06:11:48.382868Z","caller":"traceutil/trace.go:171","msg":"trace[930067052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:862; }","duration":"322.377747ms","start":"2026-02-25T06:11:48.06048Z","end":"2026-02-25T06:11:48.382858Z","steps":["trace[930067052] 'agreement among raft nodes before linearized reading' (duration: 322.280964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:48.382903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:48.060468Z","time spent":"322.428919ms","remote":"127.0.0.1:48170","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-25T06:11:48.382996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.475916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:11:48.383035Z","caller":"traceutil/trace.go:171","msg":"trace[1089051910] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:862; }","duration":"341.554998ms","start":"2026-02-25T06:11:48.041466Z","end":"2026-02-25T06:11:48.383021Z","steps":["trace[1089051910] 'agreement among raft nodes before linearized reading' (duration: 341.474106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:48.38245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:47.878171Z","time spent":"504.271667ms","remote":"127.0.0.1:48170","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-25T06:11:48.383059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:48.041447Z","time spent":"341.60627ms","remote":"127.0.0.1:48414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:11:48.383267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.624877ms","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-25T06:11:48.383324Z","caller":"traceutil/trace.go:171","msg":"trace[1604239266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:862; }","duration":"323.749712ms","start":"2026-02-25T06:11:48.059556Z","end":"2026-02-25T06:11:48.383306Z","steps":["trace[1604239266] 'agreement among raft nodes before linearized reading' (duration: 323.495903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:48.383418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:48.05953Z","time spent":"323.877286ms","remote":"127.0.0.1:48170","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-25T06:11:49.202731Z","caller":"traceutil/trace.go:171","msg":"trace[1390348718] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"813.94079ms","start":"2026-02-25T06:11:48.388763Z","end":"2026-02-25T06:11:49.202704Z","steps":["trace[1390348718] 'process raft request' (duration: 813.786385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:49.202912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:48.388749Z","time spent":"814.061403ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:49.25391Z","caller":"traceutil/trace.go:171","msg":"trace[218594086] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"863.538231ms","start":"2026-02-25T06:11:48.39036Z","end":"2026-02-25T06:11:49.253898Z","steps":["trace[218594086] 'process raft request' (duration: 863.477939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:49.254137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:48.390342Z","time spent":"863.676614ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:49.254288Z","caller":"traceutil/trace.go:171","msg":"trace[1417672867] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"864.722627ms","start":"2026-02-25T06:11:48.389519Z","end":"2026-02-25T06:11:49.254241Z","steps":["trace[1417672867] 'process raft request' (duration: 864.139319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:49.25448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:48.38951Z","time spent":"864.862683ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:49.253777Z","caller":"traceutil/trace.go:171","msg":"trace[226733727] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"864.296214ms","start":"2026-02-25T06:11:48.389427Z","end":"2026-02-25T06:11:49.253723Z","steps":["trace[226733727] 'process raft request' (duration: 864.022665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:49.254584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:48.389401Z","time spent":"865.141411ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:08.927686Z","caller":"traceutil/trace.go:171","msg":"trace[1478734029] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"262.125307ms","start":"2026-02-25T06:12:08.665524Z","end":"2026-02-25T06:12:08.927649Z","steps":["trace[1478734029] 'process raft request' (duration: 261.92318ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:08.928914Z","caller":"traceutil/trace.go:171","msg":"trace[1974649913] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"200.928876ms","start":"2026-02-25T06:12:08.727954Z","end":"2026-02-25T06:12:08.928883Z","steps":["trace[1974649913] 'process raft request' (duration: 200.74224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:10.665399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.535271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:10.665497Z","caller":"traceutil/trace.go:171","msg":"trace[1033122515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"133.642494ms","start":"2026-02-25T06:12:10.531831Z","end":"2026-02-25T06:12:10.665473Z","steps":["trace[1033122515] 'range keys from in-memory index tree' (duration: 133.448288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:10.665804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209178299s","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-25T06:12:10.665883Z","caller":"traceutil/trace.go:171","msg":"trace[1734862684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"1.209308994s","start":"2026-02-25T06:12:09.456554Z","end":"2026-02-25T06:12:10.665863Z","steps":["trace[1734862684] 'range keys from in-memory index tree' (duration: 1.208998834s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:10.665921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:09.45653Z","time spent":"1.209384286s","remote":"127.0.0.1:48170","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-25T06:12:10.666179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203207003s","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-25T06:12:10.666207Z","caller":"traceutil/trace.go:171","msg":"trace[1413560677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"1.203249584s","start":"2026-02-25T06:12:09.462949Z","end":"2026-02-25T06:12:10.666198Z","steps":["trace[1413560677] 'range keys from in-memory index tree' (duration: 1.20312636s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:10.666232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:09.462941Z","time spent":"1.203284875s","remote":"127.0.0.1:48170","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-25T06:12:10.666356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202074267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T06:12:10.666389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.041272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:12:10.666432Z","caller":"traceutil/trace.go:171","msg":"trace[1862125180] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:946; }","duration":"131.142566ms","start":"2026-02-25T06:12:10.535277Z","end":"2026-02-25T06:12:10.66642Z","steps":["trace[1862125180] 'count revisions from in-memory index tree' (duration: 130.845336ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:10.666433Z","caller":"traceutil/trace.go:171","msg":"trace[1915896445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"1.20218044s","start":"2026-02-25T06:12:09.464231Z","end":"2026-02-25T06:12:10.666412Z","steps":["trace[1915896445] 'range keys from in-memory index tree' (duration: 1.201931402s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:10.666479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:09.464223Z","time spent":"1.202247912s","remote":"127.0.0.1:48170","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-25T06:12:11.289799Z","caller":"traceutil/trace.go:171","msg":"trace[1938980396] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"608.587001ms","start":"2026-02-25T06:12:10.68119Z","end":"2026-02-25T06:12:11.289777Z","steps":["trace[1938980396] 'process raft request' (duration: 608.420075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:11.289948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:10.681177Z","time spent":"608.696294ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:11.808833Z","caller":"traceutil/trace.go:171","msg":"trace[167504461] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.127164616s","start":"2026-02-25T06:12:10.681595Z","end":"2026-02-25T06:12:11.80876Z","steps":["trace[167504461] 'process raft request' (duration: 1.126696841s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:11.809375Z","caller":"traceutil/trace.go:171","msg":"trace[1630391676] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.127277739s","start":"2026-02-25T06:12:10.68207Z","end":"2026-02-25T06:12:11.809347Z","steps":["trace[1630391676] 'process raft request' (duration: 1.126469914s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:11.809548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:10.682045Z","time spent":"1.127408304s","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:11.809259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:10.681571Z","time spent":"1.127351572s","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:11.810876Z","caller":"traceutil/trace.go:171","msg":"trace[1007354583] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:990; }","duration":"876.699165ms","start":"2026-02-25T06:12:10.93415Z","end":"2026-02-25T06:12:11.810849Z","steps":["trace[1007354583] 'read index received' (duration: 356.099506ms)","trace[1007354583] 'applied index is now lower than readState.Index' (duration: 520.598549ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:11.811098Z","caller":"traceutil/trace.go:171","msg":"trace[747600780] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"994.888044ms","start":"2026-02-25T06:12:10.816123Z","end":"2026-02-25T06:12:11.811011Z","steps":["trace[747600780] 'process raft request' (duration: 994.641687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:11.811287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:10.816098Z","time spent":"995.04979ms","remote":"127.0.0.1:48064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5381,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:11.811678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.532511ms","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-25T06:12:11.811912Z","caller":"traceutil/trace.go:171","msg":"trace[1389034754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:950; }","duration":"877.619953ms","start":"2026-02-25T06:12:10.934093Z","end":"2026-02-25T06:12:11.811713Z","steps":["trace[1389034754] 'agreement among raft nodes before linearized reading' (duration: 877.445268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:11.811991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:10.93407Z","time spent":"877.882072ms","remote":"127.0.0.1:48170","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-25T06:12:12.273334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.541895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:12.273566Z","caller":"traceutil/trace.go:171","msg":"trace[295894399] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"598.792203ms","start":"2026-02-25T06:12:11.674736Z","end":"2026-02-25T06:12:12.273528Z","steps":["trace[295894399] 'process raft request' (duration: 302.963538ms)","trace[295894399] 'compare' (duration: 295.306548ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:12.273694Z","caller":"traceutil/trace.go:171","msg":"trace[675888573] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"456.242549ms","start":"2026-02-25T06:12:11.817434Z","end":"2026-02-25T06:12:12.273677Z","steps":["trace[675888573] 'process raft request' (duration: 455.999242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.273756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.817417Z","time spent":"456.310761ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:12.273764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.674706Z","time spent":"598.952148ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:12.273859Z","caller":"traceutil/trace.go:171","msg":"trace[362572348] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"462.876698ms","start":"2026-02-25T06:12:11.810947Z","end":"2026-02-25T06:12:12.273824Z","steps":["trace[362572348] 'read index received' (duration: 166.770325ms)","trace[362572348] 'applied index is now lower than readState.Index' (duration: 296.102233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:12.27406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.174701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:12.274097Z","caller":"traceutil/trace.go:171","msg":"trace[1386185543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"741.274884ms","start":"2026-02-25T06:12:11.532811Z","end":"2026-02-25T06:12:12.274086Z","steps":["trace[1386185543] 'agreement among raft nodes before linearized reading' (duration: 741.166601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.274125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.532768Z","time spent":"741.351677ms","remote":"127.0.0.1:47862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:12:12.274245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023613236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T06:12:12.274243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.542664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:12:12.274276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.598457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-02-25T06:12:12.274285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.260012ms","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-25T06:12:12.274308Z","caller":"traceutil/trace.go:171","msg":"trace[57146688] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:952; }","duration":"1.023745991s","start":"2026-02-25T06:12:11.250545Z","end":"2026-02-25T06:12:12.274291Z","steps":["trace[57146688] 'agreement among raft nodes before linearized reading' (duration: 1.023619077s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:12.274312Z","caller":"traceutil/trace.go:171","msg":"trace[2000224547] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:952; }","duration":"742.624597ms","start":"2026-02-25T06:12:11.531671Z","end":"2026-02-25T06:12:12.274296Z","steps":["trace[2000224547] 'agreement among raft nodes before linearized reading' (duration: 742.495503ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:12.274334Z","caller":"traceutil/trace.go:171","msg":"trace[1831003101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:952; }","duration":"898.352275ms","start":"2026-02-25T06:12:11.375971Z","end":"2026-02-25T06:12:12.274323Z","steps":["trace[1831003101] 'agreement among raft nodes before linearized reading' (duration: 898.240451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.274343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.250506Z","time spent":"1.023829753s","remote":"127.0.0.1:48388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:12:12.27435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.531604Z","time spent":"742.73776ms","remote":"127.0.0.1:48404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:12:12.274371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.375938Z","time spent":"898.425477ms","remote":"127.0.0.1:48170","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":"info","ts":"2026-02-25T06:12:12.274317Z","caller":"traceutil/trace.go:171","msg":"trace[1814702568] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:952; }","duration":"410.665049ms","start":"2026-02-25T06:12:11.863639Z","end":"2026-02-25T06:12:12.274304Z","steps":["trace[1814702568] 'agreement among raft nodes before linearized reading' (duration: 410.542635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.274435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.863624Z","time spent":"410.803583ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T06:12:12.274465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.74408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:12:12.274535Z","caller":"traceutil/trace.go:171","msg":"trace[862802952] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:952; }","duration":"279.863964ms","start":"2026-02-25T06:12:11.994655Z","end":"2026-02-25T06:12:12.274519Z","steps":["trace[862802952] 'agreement among raft nodes before linearized reading' (duration: 279.7262ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:12.525219Z","caller":"traceutil/trace.go:171","msg":"trace[284343427] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"236.13927ms","start":"2026-02-25T06:12:12.289054Z","end":"2026-02-25T06:12:12.525193Z","steps":["trace[284343427] 'process raft request' (duration: 235.964815ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:12.576845Z","caller":"traceutil/trace.go:171","msg":"trace[137748276] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"287.592295ms","start":"2026-02-25T06:12:12.289178Z","end":"2026-02-25T06:12:12.57677Z","steps":["trace[137748276] 'process raft request' (duration: 287.180252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.998292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.761387ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T06:12:12.998411Z","caller":"traceutil/trace.go:171","msg":"trace[488395527] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:995; }","duration":"669.472951ms","start":"2026-02-25T06:12:12.328916Z","end":"2026-02-25T06:12:12.998389Z","steps":["trace[488395527] 'read index received' (duration: 195.98928ms)","trace[488395527] 'applied index is now lower than readState.Index' (duration: 473.482111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:12.998504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.571923ms","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-25T06:12:12.99854Z","caller":"traceutil/trace.go:171","msg":"trace[919917949] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:954; }","duration":"669.668077ms","start":"2026-02-25T06:12:12.32886Z","end":"2026-02-25T06:12:12.998528Z","steps":["trace[919917949] 'agreement among raft nodes before linearized reading' (duration: 669.582084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.998608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:12.328842Z","time spent":"669.752269ms","remote":"127.0.0.1:48130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:12:14.187204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.260785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:14.187333Z","caller":"traceutil/trace.go:171","msg":"trace[1621150117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"640.484082ms","start":"2026-02-25T06:12:13.54682Z","end":"2026-02-25T06:12:14.187304Z","steps":["trace[1621150117] 'range keys from in-memory index tree' (duration: 640.157832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:14.1874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:13.546793Z","time spent":"640.596136ms","remote":"127.0.0.1:47862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:12:14.187376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.015267ms","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":"warn","ts":"2026-02-25T06:12:14.187376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.682167ms","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-25T06:12:14.187469Z","caller":"traceutil/trace.go:171","msg":"trace[1184671619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"892.851213ms","start":"2026-02-25T06:12:13.2946Z","end":"2026-02-25T06:12:14.187451Z","steps":["trace[1184671619] 'range keys from in-memory index tree' (duration: 892.43906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:14.187483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.46589ms","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-25T06:12:14.187469Z","caller":"traceutil/trace.go:171","msg":"trace[289689133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"373.161822ms","start":"2026-02-25T06:12:13.814281Z","end":"2026-02-25T06:12:14.187443Z","steps":["trace[289689133] 'range keys from in-memory index tree' (duration: 372.822921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:14.187536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:13.294573Z","time spent":"892.949236ms","remote":"127.0.0.1:48170","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-25T06:12:14.187544Z","caller":"traceutil/trace.go:171","msg":"trace[1490972656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"372.537462ms","start":"2026-02-25T06:12:13.814989Z","end":"2026-02-25T06:12:14.187526Z","steps":["trace[1490972656] 'range keys from in-memory index tree' (duration: 372.364757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:14.187587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:13.814984Z","time spent":"372.591015ms","remote":"127.0.0.1:48170","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-25T06:12:14.187582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:13.814258Z","time spent":"373.309596ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T06:12:14.682806Z","caller":"traceutil/trace.go:171","msg":"trace[2073020850] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"488.846002ms","start":"2026-02-25T06:12:14.193928Z","end":"2026-02-25T06:12:14.682774Z","steps":["trace[2073020850] 'process raft request' (duration: 488.656726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:14.683012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:14.193896Z","time spent":"489.008768ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:14.854026Z","caller":"traceutil/trace.go:171","msg":"trace[1845699530] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:999; }","duration":"573.799959ms","start":"2026-02-25T06:12:14.280198Z","end":"2026-02-25T06:12:14.853998Z","steps":["trace[1845699530] 'read index received' (duration: 402.39327ms)","trace[1845699530] 'applied index is now lower than readState.Index' (duration: 171.405239ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:14.854297Z","caller":"traceutil/trace.go:171","msg":"trace[424352588] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"658.959312ms","start":"2026-02-25T06:12:14.195324Z","end":"2026-02-25T06:12:14.854283Z","steps":["trace[424352588] 'process raft request' (duration: 642.881648ms)","trace[424352588] 'compare' (duration: 15.61988ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:14.854437Z","caller":"traceutil/trace.go:171","msg":"trace[1682507171] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"657.101734ms","start":"2026-02-25T06:12:14.197322Z","end":"2026-02-25T06:12:14.854424Z","steps":["trace[1682507171] 'process raft request' (duration: 656.632229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:14.854525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:14.19731Z","time spent":"657.158925ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:14.854826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.601214ms","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-25T06:12:14.854964Z","caller":"traceutil/trace.go:171","msg":"trace[974944791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"574.810401ms","start":"2026-02-25T06:12:14.280133Z","end":"2026-02-25T06:12:14.854943Z","steps":["trace[974944791] 'agreement among raft nodes before linearized reading' (duration: 574.419349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:14.854995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:14.195306Z","time spent":"659.030594ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:14.855129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:14.280109Z","time spent":"574.985947ms","remote":"127.0.0.1:48170","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-25T06:12:14.855244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.177339ms","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-25T06:12:14.85568Z","caller":"traceutil/trace.go:171","msg":"trace[1088014772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:958; }","duration":"320.681184ms","start":"2026-02-25T06:12:14.534974Z","end":"2026-02-25T06:12:14.855655Z","steps":["trace[1088014772] 'agreement among raft nodes before linearized reading' (duration: 319.501557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:14.855751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:14.534942Z","time spent":"320.796048ms","remote":"127.0.0.1:48170","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":"info","ts":"2026-02-25T06:12:15.006529Z","caller":"traceutil/trace.go:171","msg":"trace[1925741958] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"144.257958ms","start":"2026-02-25T06:12:14.862182Z","end":"2026-02-25T06:12:15.00644Z","steps":["trace[1925741958] 'process raft request' (duration: 97.867612ms)","trace[1925741958] 'compare' (duration: 46.002224ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:15.303376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.329263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:15.303509Z","caller":"traceutil/trace.go:171","msg":"trace[578679698] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"130.420183ms","start":"2026-02-25T06:12:15.173068Z","end":"2026-02-25T06:12:15.303488Z","steps":["trace[578679698] 'read index received' (duration: 50.742µs)","trace[578679698] 'applied index is now lower than readState.Index' (duration: 130.366561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:15.303855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.781144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:15.303862Z","caller":"traceutil/trace.go:171","msg":"trace[1742885] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"218.104125ms","start":"2026-02-25T06:12:15.085711Z","end":"2026-02-25T06:12:15.303815Z","steps":["trace[1742885] 'process raft request' (duration: 34.233444ms)","trace[1742885] 'compare' (duration: 183.028474ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:15.303904Z","caller":"traceutil/trace.go:171","msg":"trace[75729718] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:961; }","duration":"130.881077ms","start":"2026-02-25T06:12:15.17301Z","end":"2026-02-25T06:12:15.303891Z","steps":["trace[75729718] 'agreement among raft nodes before linearized reading' (duration: 130.532766ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:15.503584Z","caller":"traceutil/trace.go:171","msg":"trace[1613438921] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"190.947383ms","start":"2026-02-25T06:12:15.312604Z","end":"2026-02-25T06:12:15.503552Z","steps":["trace[1613438921] 'process raft request' (duration: 109.193977ms)","trace[1613438921] 'compare' (duration: 81.600071ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:15.527562Z","caller":"traceutil/trace.go:171","msg":"trace[704013908] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"174.988742ms","start":"2026-02-25T06:12:15.352507Z","end":"2026-02-25T06:12:15.527496Z","steps":["trace[704013908] 'process raft request' (duration: 174.756835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:16.91009Z","caller":"traceutil/trace.go:171","msg":"trace[803841948] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"216.306829ms","start":"2026-02-25T06:12:16.693741Z","end":"2026-02-25T06:12:16.910048Z","steps":["trace[803841948] 'process raft request' (duration: 216.00415ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:17.056958Z","caller":"traceutil/trace.go:171","msg":"trace[498219130] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"141.740778ms","start":"2026-02-25T06:12:16.915168Z","end":"2026-02-25T06:12:17.056909Z","steps":["trace[498219130] 'process raft request' (duration: 79.03389ms)","trace[498219130] 'compare' (duration: 62.404749ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:17.057117Z","caller":"traceutil/trace.go:171","msg":"trace[603462280] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"141.832282ms","start":"2026-02-25T06:12:16.915253Z","end":"2026-02-25T06:12:17.057085Z","steps":["trace[603462280] 'process raft request' (duration: 141.517382ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:17.536178Z","caller":"traceutil/trace.go:171","msg":"trace[10570739] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"420.652832ms","start":"2026-02-25T06:12:17.115491Z","end":"2026-02-25T06:12:17.536144Z","steps":["trace[10570739] 'read index received' (duration: 397.512186ms)","trace[10570739] 'applied index is now lower than readState.Index' (duration: 23.139226ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:17.53642Z","caller":"traceutil/trace.go:171","msg":"trace[1418186725] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"474.276345ms","start":"2026-02-25T06:12:17.062099Z","end":"2026-02-25T06:12:17.536375Z","steps":["trace[1418186725] 'process raft request' (duration: 451.013416ms)","trace[1418186725] 'compare' (duration: 22.897758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:17.536604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:17.062073Z","time spent":"474.43082ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:17.536714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.203219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T06:12:17.536763Z","caller":"traceutil/trace.go:171","msg":"trace[1318221896] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:971; }","duration":"421.321233ms","start":"2026-02-25T06:12:17.115427Z","end":"2026-02-25T06:12:17.536748Z","steps":["trace[1318221896] 'agreement among raft nodes before linearized reading' (duration: 420.831218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:17.536829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:17.115406Z","time spent":"421.384626ms","remote":"127.0.0.1:47908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"info","ts":"2026-02-25T06:12:19.106346Z","caller":"traceutil/trace.go:171","msg":"trace[1447625019] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"120.649907ms","start":"2026-02-25T06:12:18.985649Z","end":"2026-02-25T06:12:19.106299Z","steps":["trace[1447625019] 'process raft request' (duration: 67.159518ms)","trace[1447625019] 'compare' (duration: 53.298673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:19.538576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.559011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:19.538679Z","caller":"traceutil/trace.go:171","msg":"trace[1891878078] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"410.131072ms","start":"2026-02-25T06:12:19.12853Z","end":"2026-02-25T06:12:19.538661Z","steps":["trace[1891878078] 'read index received' (duration: 42.939018ms)","trace[1891878078] 'applied index is now lower than readState.Index' (duration: 367.190914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:19.538731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.217054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:12:19.538746Z","caller":"traceutil/trace.go:171","msg":"trace[242400655] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:978; }","duration":"410.256806ms","start":"2026-02-25T06:12:19.128482Z","end":"2026-02-25T06:12:19.538739Z","steps":["trace[242400655] 'agreement among raft nodes before linearized reading' (duration: 410.216845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:19.538771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:19.128467Z","time spent":"410.296478ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:12:19.538761Z","caller":"traceutil/trace.go:171","msg":"trace[1027655420] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"425.835565ms","start":"2026-02-25T06:12:19.112898Z","end":"2026-02-25T06:12:19.538734Z","steps":["trace[1027655420] 'process raft request' (duration: 300.030456ms)","trace[1027655420] 'compare' (duration: 125.441637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:19.538921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:19.112873Z","time spent":"425.960729ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:21.409443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.664184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:21.409514Z","caller":"traceutil/trace.go:171","msg":"trace[660697427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"876.772838ms","start":"2026-02-25T06:12:20.532725Z","end":"2026-02-25T06:12:21.409498Z","steps":["trace[660697427] 'range keys from in-memory index tree' (duration: 876.532929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.409546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.532712Z","time spent":"876.826919ms","remote":"127.0.0.1:47862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:12:21.409528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.276541ms","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-25T06:12:21.409638Z","caller":"traceutil/trace.go:171","msg":"trace[1282325971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"298.467087ms","start":"2026-02-25T06:12:21.111142Z","end":"2026-02-25T06:12:21.409609Z","steps":["trace[1282325971] 'range keys from in-memory index tree' (duration: 297.988302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.409733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.212599ms","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-25T06:12:21.409825Z","caller":"traceutil/trace.go:171","msg":"trace[1549187107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"478.342402ms","start":"2026-02-25T06:12:20.931458Z","end":"2026-02-25T06:12:21.409801Z","steps":["trace[1549187107] 'range keys from in-memory index tree' (duration: 478.016462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.409873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.931445Z","time spent":"478.418345ms","remote":"127.0.0.1:48170","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-25T06:12:21.409911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.74295ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:21.409981Z","caller":"traceutil/trace.go:171","msg":"trace[1095147089] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:986; }","duration":"265.812682ms","start":"2026-02-25T06:12:21.144157Z","end":"2026-02-25T06:12:21.40997Z","steps":["trace[1095147089] 'range keys from in-memory index tree' (duration: 265.72814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.409937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.504058ms","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-25T06:12:21.410044Z","caller":"traceutil/trace.go:171","msg":"trace[1819947410] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:986; }","duration":"832.691074ms","start":"2026-02-25T06:12:20.577323Z","end":"2026-02-25T06:12:21.410015Z","steps":["trace[1819947410] 'range keys from in-memory index tree' (duration: 832.277511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.410078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.000083ms","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-25T06:12:21.410116Z","caller":"traceutil/trace.go:171","msg":"trace[1260186899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"234.088597ms","start":"2026-02-25T06:12:21.176016Z","end":"2026-02-25T06:12:21.410104Z","steps":["trace[1260186899] 'range keys from in-memory index tree' (duration: 233.854539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.410926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.577291Z","time spent":"832.795317ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T06:12:22.045137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218092340458906,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:12:22.317595Z","caller":"traceutil/trace.go:171","msg":"trace[965057472] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"901.962958ms","start":"2026-02-25T06:12:21.415605Z","end":"2026-02-25T06:12:22.317568Z","steps":["trace[965057472] 'process raft request' (duration: 901.753092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:22.317776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.415582Z","time spent":"902.102692ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:22.502686Z","caller":"traceutil/trace.go:171","msg":"trace[1358381633] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"1.086525061s","start":"2026-02-25T06:12:21.416071Z","end":"2026-02-25T06:12:22.502596Z","steps":["trace[1358381633] 'process raft request' (duration: 1.086207261s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:22.502923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.416023Z","time spent":"1.086768299s","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:22.502939Z","caller":"traceutil/trace.go:171","msg":"trace[63256961] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.08683434s","start":"2026-02-25T06:12:21.416071Z","end":"2026-02-25T06:12:22.502906Z","steps":["trace[63256961] 'process raft request' (duration: 1.086471319s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:22.502987Z","caller":"traceutil/trace.go:171","msg":"trace[599953667] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1031; }","duration":"958.792292ms","start":"2026-02-25T06:12:21.544163Z","end":"2026-02-25T06:12:22.502955Z","steps":["trace[599953667] 'read index received' (duration: 773.148095ms)","trace[599953667] 'applied index is now lower than readState.Index' (duration: 185.642817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:22.503117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.416024Z","time spent":"1.086988046s","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:22.503115Z","caller":"traceutil/trace.go:171","msg":"trace[1080530288] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.082064231s","start":"2026-02-25T06:12:21.421013Z","end":"2026-02-25T06:12:22.503077Z","steps":["trace[1080530288] 'process raft request' (duration: 1.081761391s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:22.503318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.143723ms","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-25T06:12:22.503339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.421Z","time spent":"1.082228056s","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:22.503357Z","caller":"traceutil/trace.go:171","msg":"trace[1175877837] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"959.263987ms","start":"2026-02-25T06:12:21.544081Z","end":"2026-02-25T06:12:22.503345Z","steps":["trace[1175877837] 'agreement among raft nodes before linearized reading' (duration: 959.03499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:22.503452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.747615ms","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-25T06:12:22.503539Z","caller":"traceutil/trace.go:171","msg":"trace[86391911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"918.912291ms","start":"2026-02-25T06:12:21.584606Z","end":"2026-02-25T06:12:22.503519Z","steps":["trace[86391911] 'agreement among raft nodes before linearized reading' (duration: 918.552589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:22.503592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.58458Z","time spent":"919.004133ms","remote":"127.0.0.1:48170","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-25T06:12:22.504446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.544053Z","time spent":"959.344649ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T06:12:25.869987Z","caller":"traceutil/trace.go:171","msg":"trace[915579155] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"375.05166ms","start":"2026-02-25T06:12:25.494899Z","end":"2026-02-25T06:12:25.869951Z","steps":["trace[915579155] 'process raft request' (duration: 374.74309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:25.87032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:25.494858Z","time spent":"375.246797ms","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13250,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:25.870551Z","caller":"traceutil/trace.go:171","msg":"trace[1807295704] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"375.135593ms","start":"2026-02-25T06:12:25.495389Z","end":"2026-02-25T06:12:25.870525Z","steps":["trace[1807295704] 'process raft request' (duration: 374.944677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:25.870772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:25.495369Z","time spent":"375.303399ms","remote":"127.0.0.1:48170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:42.651901Z","caller":"traceutil/trace.go:171","msg":"trace[1715141384] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1135; }","duration":"106.625346ms","start":"2026-02-25T06:12:42.545247Z","end":"2026-02-25T06:12:42.651872Z","steps":["trace[1715141384] 'read index received' (duration: 37.668982ms)","trace[1715141384] 'applied index is now lower than readState.Index' (duration: 68.954624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:42.652104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.836643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:42.652527Z","caller":"traceutil/trace.go:171","msg":"trace[1419389532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"107.325879ms","start":"2026-02-25T06:12:42.54517Z","end":"2026-02-25T06:12:42.652496Z","steps":["trace[1419389532] 'agreement among raft nodes before linearized reading' (duration: 106.811193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:42.652175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.138155ms","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-25T06:12:42.652685Z","caller":"traceutil/trace.go:171","msg":"trace[1298347963] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1086; }","duration":"101.65746ms","start":"2026-02-25T06:12:42.550982Z","end":"2026-02-25T06:12:42.652639Z","steps":["trace[1298347963] 'agreement among raft nodes before linearized reading' (duration: 101.056451ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:58.767149Z","caller":"traceutil/trace.go:171","msg":"trace[951853944] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"208.969088ms","start":"2026-02-25T06:12:58.558148Z","end":"2026-02-25T06:12:58.767117Z","steps":["trace[951853944] 'process raft request' (duration: 208.639568ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:21.670489Z","caller":"traceutil/trace.go:171","msg":"trace[1801416240] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"136.504334ms","start":"2026-02-25T06:13:21.533955Z","end":"2026-02-25T06:13:21.67046Z","steps":["trace[1801416240] 'read index received' (duration: 136.243906ms)","trace[1801416240] 'applied index is now lower than readState.Index' (duration: 258.908µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:21.670557Z","caller":"traceutil/trace.go:171","msg":"trace[380696196] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"212.749113ms","start":"2026-02-25T06:13:21.457762Z","end":"2026-02-25T06:13:21.670511Z","steps":["trace[380696196] 'process raft request' (duration: 212.465824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:21.670636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.6513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:21.67068Z","caller":"traceutil/trace.go:171","msg":"trace[535512658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"136.743231ms","start":"2026-02-25T06:13:21.533922Z","end":"2026-02-25T06:13:21.670665Z","steps":["trace[535512658] 'agreement among raft nodes before linearized reading' (duration: 136.653979ms)"],"step_count":1}