{"level":"warn","ts":"2026-02-24T04:30:19.441837Z","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-24T04:30:19.441964Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-24T04:30:19.442084Z","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-24T04:30:19.4421Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-24T04:30:19.442136Z","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-24T04:30:19.44304Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-02-24T04:30:19.443144Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-24T04:30:19.44806Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.64092ms"} {"level":"info","ts":"2026-02-24T04:30:19.456719Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-02-24T04:30:19.456794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T04:30:19.45682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-02-24T04:30:19.456829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T04:30:19.456839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-02-24T04:30:19.456871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-02-24T04:30:19.469984Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T04:30:19.473258Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T04:30:19.47489Z","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-24T04:30:19.477496Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T04:30:19.477792Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T04:30:19.479068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-02-24T04:30:19.479268Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-24T04:30:19.479442Z","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-24T04:30:19.479587Z","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-24T04:30:19.479596Z","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-24T04:30:19.481113Z","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-24T04:30:19.481299Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-24T04:30:19.481336Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-24T04:30:19.481748Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T04:30:19.48179Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T04:30:19.657782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T04:30:19.657843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T04:30:19.65789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-02-24T04:30:19.657916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-02-24T04:30:19.657929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-24T04:30:19.657948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-02-24T04:30:19.657965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-24T04:30:19.658875Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:30:19.65971Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T04:30:19.659886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:30:19.65996Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:30:19.660339Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:30:19.660462Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:30:19.660573Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:30:19.660718Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T04:30:19.660756Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T04:30:19.662978Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-02-24T04:30:19.663137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T04:30:32.116219Z","caller":"traceutil/trace.go:171","msg":"trace[1325369913] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"108.191677ms","start":"2026-02-24T04:30:32.007998Z","end":"2026-02-24T04:30:32.11619Z","steps":["trace[1325369913] 'process raft request' (duration: 108.023446ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:32.236513Z","caller":"traceutil/trace.go:171","msg":"trace[1581018817] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"113.372286ms","start":"2026-02-24T04:30:32.123117Z","end":"2026-02-24T04:30:32.236489Z","steps":["trace[1581018817] 'process raft request' (duration: 108.223397ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:38.249404Z","caller":"traceutil/trace.go:171","msg":"trace[1801509944] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"111.006935ms","start":"2026-02-24T04:30:38.138331Z","end":"2026-02-24T04:30:38.249338Z","steps":["trace[1801509944] 'read index received' (duration: 82.357386ms)","trace[1801509944] 'applied index is now lower than readState.Index' (duration: 28.648459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:38.249575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.184986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:30:38.249647Z","caller":"traceutil/trace.go:171","msg":"trace[877658229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:453; }","duration":"111.348787ms","start":"2026-02-24T04:30:38.138283Z","end":"2026-02-24T04:30:38.249632Z","steps":["trace[877658229] 'agreement among raft nodes before linearized reading' (duration: 111.196236ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:38.249884Z","caller":"traceutil/trace.go:171","msg":"trace[1027204401] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"126.461591ms","start":"2026-02-24T04:30:38.123381Z","end":"2026-02-24T04:30:38.249843Z","steps":["trace[1027204401] 'process raft request' (duration: 97.35476ms)","trace[1027204401] 'compare' (duration: 28.481417ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:38.673421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.593628ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-24T04:30:50.375972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.3262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:30:50.376114Z","caller":"traceutil/trace.go:171","msg":"trace[1425007565] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"535.551111ms","start":"2026-02-24T04:30:49.84054Z","end":"2026-02-24T04:30:50.376091Z","steps":["trace[1425007565] 'read index received' (duration: 159.405897ms)","trace[1425007565] 'applied index is now lower than readState.Index' (duration: 376.143154ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:50.376173Z","caller":"traceutil/trace.go:171","msg":"trace[520381706] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"855.218442ms","start":"2026-02-24T04:30:49.520916Z","end":"2026-02-24T04:30:50.376134Z","steps":["trace[520381706] 'process raft request' (duration: 479.181939ms)","trace[520381706] 'compare' (duration: 375.024648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:50.376286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.966885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:30:50.376341Z","caller":"traceutil/trace.go:171","msg":"trace[921906511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"237.027205ms","start":"2026-02-24T04:30:50.139288Z","end":"2026-02-24T04:30:50.376315Z","steps":["trace[921906511] 'agreement among raft nodes before linearized reading' (duration: 236.949075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:50.376335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.139565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"warn","ts":"2026-02-24T04:30:50.376337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.774302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T04:30:50.376425Z","caller":"traceutil/trace.go:171","msg":"trace[777649345] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:512; }","duration":"353.283426ms","start":"2026-02-24T04:30:50.023118Z","end":"2026-02-24T04:30:50.376401Z","steps":["trace[777649345] 'agreement among raft nodes before linearized reading' (duration: 353.116505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:50.376459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.719587ms","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-24T04:30:50.376485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:50.023092Z","time spent":"353.384076ms","remote":"127.0.0.1:48436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4043,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-24T04:30:50.376462Z","caller":"traceutil/trace.go:171","msg":"trace[89001891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"535.948884ms","start":"2026-02-24T04:30:49.840486Z","end":"2026-02-24T04:30:50.376435Z","steps":["trace[89001891] 'agreement among raft nodes before linearized reading' (duration: 535.726923ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:50.376532Z","caller":"traceutil/trace.go:171","msg":"trace[1303567955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"316.834087ms","start":"2026-02-24T04:30:50.059677Z","end":"2026-02-24T04:30:50.376511Z","steps":["trace[1303567955] 'agreement among raft nodes before linearized reading' (duration: 316.585096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:50.376595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:49.840467Z","time spent":"536.112535ms","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:30:50.376586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:50.059665Z","time spent":"316.909478ms","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:30:50.37629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:49.520886Z","time spent":"855.345073ms","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:50.483284Z","caller":"traceutil/trace.go:171","msg":"trace[1475990473] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"102.170618ms","start":"2026-02-24T04:30:50.381094Z","end":"2026-02-24T04:30:50.483265Z","steps":["trace[1475990473] 'process raft request' (duration: 102.053138ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:50.487217Z","caller":"traceutil/trace.go:171","msg":"trace[202169016] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"105.591536ms","start":"2026-02-24T04:30:50.381605Z","end":"2026-02-24T04:30:50.487197Z","steps":["trace[202169016] 'process raft request' (duration: 105.418136ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:50.48762Z","caller":"traceutil/trace.go:171","msg":"trace[297912566] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"103.446805ms","start":"2026-02-24T04:30:50.384128Z","end":"2026-02-24T04:30:50.487575Z","steps":["trace[297912566] 'process raft request' (duration: 102.991093ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:52.574592Z","caller":"traceutil/trace.go:171","msg":"trace[935851108] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"344.004502ms","start":"2026-02-24T04:30:52.230573Z","end":"2026-02-24T04:30:52.574578Z","steps":["trace[935851108] 'process raft request' (duration: 343.803031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:52.574738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:52.230553Z","time spent":"344.107542ms","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:30:52.574877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.649947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T04:30:52.574976Z","caller":"traceutil/trace.go:171","msg":"trace[1523668244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"192.796358ms","start":"2026-02-24T04:30:52.382154Z","end":"2026-02-24T04:30:52.574951Z","steps":["trace[1523668244] 'agreement among raft nodes before linearized reading' (duration: 192.494386ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:52.57502Z","caller":"traceutil/trace.go:171","msg":"trace[1849416866] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"192.324054ms","start":"2026-02-24T04:30:52.382194Z","end":"2026-02-24T04:30:52.574518Z","steps":["trace[1849416866] 'read index received' (duration: 192.139283ms)","trace[1849416866] 'applied index is now lower than readState.Index' (duration: 183.831µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:57.185492Z","caller":"traceutil/trace.go:171","msg":"trace[503775715] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"529.265839ms","start":"2026-02-24T04:30:56.656197Z","end":"2026-02-24T04:30:57.185463Z","steps":["trace[503775715] 'process raft request' (duration: 529.019688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:57.185664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:56.65618Z","time spent":"529.39757ms","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:30:57.21995Z","caller":"traceutil/trace.go:171","msg":"trace[879913820] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"160.501254ms","start":"2026-02-24T04:30:57.059424Z","end":"2026-02-24T04:30:57.219925Z","steps":["trace[879913820] 'read index received' (duration: 125.8304ms)","trace[879913820] 'applied index is now lower than readState.Index' (duration: 34.669704ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:57.220244Z","caller":"traceutil/trace.go:171","msg":"trace[835105588] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"562.812307ms","start":"2026-02-24T04:30:56.657401Z","end":"2026-02-24T04:30:57.220213Z","steps":["trace[835105588] 'process raft request' (duration: 562.391055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:57.220418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:56.657387Z","time spent":"562.938748ms","remote":"127.0.0.1:48534","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-24T04:30:57.220447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.998626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bl5r2\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-24T04:30:57.220591Z","caller":"traceutil/trace.go:171","msg":"trace[1427719572] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bl5r2; range_end:; response_count:1; response_revision:555; }","duration":"161.194388ms","start":"2026-02-24T04:30:57.059382Z","end":"2026-02-24T04:30:57.220577Z","steps":["trace[1427719572] 'agreement among raft nodes before linearized reading' (duration: 160.694825ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:30:57.960625Z","caller":"traceutil/trace.go:171","msg":"trace[389906335] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"417.33285ms","start":"2026-02-24T04:30:57.543259Z","end":"2026-02-24T04:30:57.960591Z","steps":["trace[389906335] 'read index received' (duration: 417.124679ms)","trace[389906335] 'applied index is now lower than readState.Index' (duration: 207.541µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:30:57.960694Z","caller":"traceutil/trace.go:171","msg":"trace[1531479047] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"722.214519ms","start":"2026-02-24T04:30:57.23844Z","end":"2026-02-24T04:30:57.960655Z","steps":["trace[1531479047] 'process raft request' (duration: 722.002498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:57.960853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:57.238421Z","time spent":"722.351639ms","remote":"127.0.0.1:48452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:30:57.960851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.571723ms","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-24T04:30:57.96093Z","caller":"traceutil/trace.go:171","msg":"trace[682001681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"417.684483ms","start":"2026-02-24T04:30:57.543224Z","end":"2026-02-24T04:30:57.960909Z","steps":["trace[682001681] 'agreement among raft nodes before linearized reading' (duration: 417.465732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:30:57.961014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:57.543203Z","time spent":"417.795353ms","remote":"127.0.0.1:48534","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-24T04:30:58.105079Z","caller":"traceutil/trace.go:171","msg":"trace[387813549] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"133.827108ms","start":"2026-02-24T04:30:57.971225Z","end":"2026-02-24T04:30:58.105052Z","steps":["trace[387813549] 'process raft request' (duration: 39.494817ms)","trace[387813549] 'compare' (duration: 93.942739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:30:59.156607Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289728298763431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:30:59.657505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289728298763431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:31:00.158021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289728298763431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:31:00.658974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289728298763431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:31:00.856325Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.340822911s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T04:31:02.106109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249583498s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T04:31:02.106239Z","caller":"traceutil/trace.go:171","msg":"trace[1779999943] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"3.449822103s","start":"2026-02-24T04:30:58.656385Z","end":"2026-02-24T04:31:02.106207Z","steps":["trace[1779999943] 'read index received' (duration: 2.200083968s)","trace[1779999943] 'applied index is now lower than readState.Index' (duration: 1.249736445s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:02.106484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997213369s","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-24T04:31:02.106524Z","caller":"traceutil/trace.go:171","msg":"trace[623929623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"1.997296452s","start":"2026-02-24T04:31:00.109216Z","end":"2026-02-24T04:31:02.106513Z","steps":["trace[623929623] 'agreement among raft nodes before linearized reading' (duration: 1.997142706s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:02.106516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.88103882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T04:31:02.106506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.450089194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T04:31:02.10655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:00.109194Z","time spent":"1.997350495s","remote":"127.0.0.1:48534","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":"warn","ts":"2026-02-24T04:31:02.106511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966864531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:02.106576Z","caller":"traceutil/trace.go:171","msg":"trace[726127338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"3.450214737s","start":"2026-02-24T04:30:58.656347Z","end":"2026-02-24T04:31:02.106562Z","steps":["trace[726127338] 'agreement among raft nodes before linearized reading' (duration: 3.449965547s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:02.106616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:58.656328Z","time spent":"3.45028249s","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T04:31:02.106624Z","caller":"traceutil/trace.go:171","msg":"trace[1469621122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"1.967046949s","start":"2026-02-24T04:31:00.139551Z","end":"2026-02-24T04:31:02.106598Z","steps":["trace[1469621122] 'agreement among raft nodes before linearized reading' (duration: 1.966866352s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:02.106564Z","caller":"traceutil/trace.go:171","msg":"trace[1875672601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"2.881122414s","start":"2026-02-24T04:30:59.225424Z","end":"2026-02-24T04:31:02.106546Z","steps":["trace[1875672601] 'agreement among raft nodes before linearized reading' (duration: 2.880960567s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:02.106679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:00.139521Z","time spent":"1.967146003s","remote":"127.0.0.1:48276","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-24T04:31:02.106706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:59.225396Z","time spent":"2.881290741s","remote":"127.0.0.1:48534","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-24T04:31:02.106832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.915919357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T04:31:02.107132Z","caller":"traceutil/trace.go:171","msg":"trace[2094730552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"2.916224949s","start":"2026-02-24T04:30:59.190865Z","end":"2026-02-24T04:31:02.10709Z","steps":["trace[2094730552] 'agreement among raft nodes before linearized reading' (duration: 2.915474358s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:02.107445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:30:59.190841Z","time spent":"2.916589644s","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:31:03.506004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:03.205053Z","time spent":"300.946947ms","remote":"127.0.0.1:48306","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T04:31:06.293819Z","caller":"traceutil/trace.go:171","msg":"trace[1118971654] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"114.219095ms","start":"2026-02-24T04:31:06.179565Z","end":"2026-02-24T04:31:06.293784Z","steps":["trace[1118971654] 'process raft request' (duration: 66.211348ms)","trace[1118971654] 'compare' (duration: 47.774578ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:31:06.294029Z","caller":"traceutil/trace.go:171","msg":"trace[210363495] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"114.229226ms","start":"2026-02-24T04:31:06.179774Z","end":"2026-02-24T04:31:06.294004Z","steps":["trace[210363495] 'process raft request' (duration: 113.954575ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:06.294462Z","caller":"traceutil/trace.go:171","msg":"trace[1621560531] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"113.206316ms","start":"2026-02-24T04:31:06.181234Z","end":"2026-02-24T04:31:06.294441Z","steps":["trace[1621560531] 'process raft request' (duration: 112.705226ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:06.294483Z","caller":"traceutil/trace.go:171","msg":"trace[1580244175] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"113.091191ms","start":"2026-02-24T04:31:06.181358Z","end":"2026-02-24T04:31:06.294449Z","steps":["trace[1580244175] 'process raft request' (duration: 112.910154ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:07.23951Z","caller":"traceutil/trace.go:171","msg":"trace[1560446052] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"230.654617ms","start":"2026-02-24T04:31:07.008837Z","end":"2026-02-24T04:31:07.239491Z","steps":["trace[1560446052] 'process raft request' (duration: 230.540593ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:07.240029Z","caller":"traceutil/trace.go:171","msg":"trace[978038242] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:642; }","duration":"141.434371ms","start":"2026-02-24T04:31:07.09856Z","end":"2026-02-24T04:31:07.239994Z","steps":["trace[978038242] 'read index received' (duration: 141.385829ms)","trace[978038242] 'applied index is now lower than readState.Index' (duration: 46.422µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:07.240248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.67701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-24T04:31:07.240326Z","caller":"traceutil/trace.go:171","msg":"trace[915148050] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:625; }","duration":"141.779935ms","start":"2026-02-24T04:31:07.098507Z","end":"2026-02-24T04:31:07.240287Z","steps":["trace[915148050] 'agreement among raft nodes before linearized reading' (duration: 141.623319ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:07.287115Z","caller":"traceutil/trace.go:171","msg":"trace[1361056073] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"174.81136ms","start":"2026-02-24T04:31:07.112286Z","end":"2026-02-24T04:31:07.287098Z","steps":["trace[1361056073] 'process raft request' (duration: 174.710986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:07.287218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.199585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bl5r2\" ","response":"range_response_count:1 size:17670"} {"level":"info","ts":"2026-02-24T04:31:07.287253Z","caller":"traceutil/trace.go:171","msg":"trace[1930915123] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bl5r2; range_end:; response_count:1; response_revision:626; }","duration":"175.280419ms","start":"2026-02-24T04:31:07.111961Z","end":"2026-02-24T04:31:07.287242Z","steps":["trace[1930915123] 'agreement among raft nodes before linearized reading' (duration: 175.136023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:07.658538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.147635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:31:07.658619Z","caller":"traceutil/trace.go:171","msg":"trace[1314762395] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"408.521094ms","start":"2026-02-24T04:31:07.250085Z","end":"2026-02-24T04:31:07.658606Z","steps":["trace[1314762395] 'process raft request' (duration: 206.243834ms)","trace[1314762395] 'compare' (duration: 202.052842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:07.658661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:07.250062Z","time spent":"408.579437ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:31:08.002296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.404019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:31:08.00263Z","caller":"traceutil/trace.go:171","msg":"trace[1265512390] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"505.373954ms","start":"2026-02-24T04:31:07.497242Z","end":"2026-02-24T04:31:08.002616Z","steps":["trace[1265512390] 'read index received' (duration: 276.597731ms)","trace[1265512390] 'applied index is now lower than readState.Index' (duration: 228.775413ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:31:08.002593Z","caller":"traceutil/trace.go:171","msg":"trace[2126846536] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"702.61316ms","start":"2026-02-24T04:31:07.299949Z","end":"2026-02-24T04:31:08.002562Z","steps":["trace[2126846536] 'process raft request' (duration: 473.872378ms)","trace[2126846536] 'compare' (duration: 228.273624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:08.002762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:07.299924Z","time spent":"702.754566ms","remote":"127.0.0.1:48452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17903,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:31:08.002749Z","caller":"traceutil/trace.go:171","msg":"trace[1861577539] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"680.515007ms","start":"2026-02-24T04:31:07.322199Z","end":"2026-02-24T04:31:08.002714Z","steps":["trace[1861577539] 'process raft request' (duration: 680.278938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:08.002945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.697057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-24T04:31:08.002996Z","caller":"traceutil/trace.go:171","msg":"trace[1980685867] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:629; }","duration":"505.77975ms","start":"2026-02-24T04:31:07.497202Z","end":"2026-02-24T04:31:08.002982Z","steps":["trace[1980685867] 'agreement among raft nodes before linearized reading' (duration: 505.590172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:08.002974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:07.322163Z","time spent":"680.732625ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T04:31:08.003039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:07.497185Z","time spent":"505.844632ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-24T04:31:08.609483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.584234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:31:08.609593Z","caller":"traceutil/trace.go:171","msg":"trace[869794415] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"865.051909ms","start":"2026-02-24T04:31:07.744507Z","end":"2026-02-24T04:31:08.609559Z","steps":["trace[869794415] 'process raft request' (duration: 385.33473ms)","trace[869794415] 'compare' (duration: 479.062634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:08.609704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:07.74448Z","time spent":"865.171874ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:31:08.609713Z","caller":"traceutil/trace.go:171","msg":"trace[1415525919] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"606.996995ms","start":"2026-02-24T04:31:08.00266Z","end":"2026-02-24T04:31:08.609657Z","steps":["trace[1415525919] 'read index received' (duration: 129.99203ms)","trace[1415525919] 'applied index is now lower than readState.Index' (duration: 477.001295ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:31:08.60987Z","caller":"traceutil/trace.go:171","msg":"trace[978736753] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"544.191968ms","start":"2026-02-24T04:31:08.065663Z","end":"2026-02-24T04:31:08.609855Z","steps":["trace[978736753] 'process raft request' (duration: 543.941179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:08.609892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.476231ms","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-24T04:31:08.609938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.065638Z","time spent":"544.269871ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:31:08.609986Z","caller":"traceutil/trace.go:171","msg":"trace[1810111794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:630; }","duration":"688.609056ms","start":"2026-02-24T04:31:07.921367Z","end":"2026-02-24T04:31:08.609976Z","steps":["trace[1810111794] 'agreement among raft nodes before linearized reading' (duration: 688.325885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:08.610024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:07.921356Z","time spent":"688.655847ms","remote":"127.0.0.1:48534","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-24T04:31:08.610104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.002539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-24T04:31:08.610249Z","caller":"traceutil/trace.go:171","msg":"trace[883704982] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:631; }","duration":"712.114153ms","start":"2026-02-24T04:31:07.898028Z","end":"2026-02-24T04:31:08.610142Z","steps":["trace[883704982] 'agreement among raft nodes before linearized reading' (duration: 711.846883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:08.6103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:07.898012Z","time spent":"712.27777ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-24T04:31:09.27272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.676651ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T04:31:09.273105Z","caller":"traceutil/trace.go:171","msg":"trace[108820141] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"663.246715ms","start":"2026-02-24T04:31:08.609831Z","end":"2026-02-24T04:31:09.273078Z","steps":["trace[108820141] 'read index received' (duration: 47.152299ms)","trace[108820141] 'applied index is now lower than readState.Index' (duration: 616.091406ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:09.273396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.078401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"warn","ts":"2026-02-24T04:31:09.273421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134970288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:09.273465Z","caller":"traceutil/trace.go:171","msg":"trace[1068558001] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:631; }","duration":"753.176075ms","start":"2026-02-24T04:31:08.520266Z","end":"2026-02-24T04:31:09.273442Z","steps":["trace[1068558001] 'agreement among raft nodes before linearized reading' (duration: 752.811181ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:09.273508Z","caller":"traceutil/trace.go:171","msg":"trace[174859835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"1.135047771s","start":"2026-02-24T04:31:08.138403Z","end":"2026-02-24T04:31:09.27345Z","steps":["trace[174859835] 'agreement among raft nodes before linearized reading' (duration: 1.134940107s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:09.273525Z","caller":"traceutil/trace.go:171","msg":"trace[576104837] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"659.571094ms","start":"2026-02-24T04:31:08.61393Z","end":"2026-02-24T04:31:09.273501Z","steps":["trace[576104837] 'process raft request' (duration: 658.894489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.273543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.692641ms","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-24T04:31:09.273607Z","caller":"traceutil/trace.go:171","msg":"trace[1220165855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"976.856158ms","start":"2026-02-24T04:31:08.296735Z","end":"2026-02-24T04:31:09.273591Z","steps":["trace[1220165855] 'agreement among raft nodes before linearized reading' (duration: 976.604168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.273605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.138388Z","time spent":"1.135205538s","remote":"127.0.0.1:48276","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-24T04:31:09.273646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.296718Z","time spent":"976.919959ms","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:31:09.273706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.613908Z","time spent":"659.679638ms","remote":"127.0.0.1:48534","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-24T04:31:09.273509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.520252Z","time spent":"753.248138ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188265,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-24T04:31:09.422516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324774999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-24T04:31:09.422609Z","caller":"traceutil/trace.go:171","msg":"trace[1653268603] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:632; }","duration":"1.324905235s","start":"2026-02-24T04:31:08.097676Z","end":"2026-02-24T04:31:09.422581Z","steps":["trace[1653268603] 'agreement among raft nodes before linearized reading' (duration: 1.175734384s)","trace[1653268603] 'range keys from in-memory index tree' (duration: 148.936763ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:09.422666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.097664Z","time spent":"1.324989427s","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-24T04:31:09.422799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122573437s","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-24T04:31:09.422875Z","caller":"traceutil/trace.go:171","msg":"trace[1982502319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.12266691s","start":"2026-02-24T04:31:08.300188Z","end":"2026-02-24T04:31:09.422855Z","steps":["trace[1982502319] 'agreement among raft nodes before linearized reading' (duration: 973.232149ms)","trace[1982502319] 'range keys from in-memory index tree' (duration: 149.288686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:09.422922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.300182Z","time spent":"1.122731282s","remote":"127.0.0.1:48534","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-24T04:31:09.423394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125309951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-24T04:31:09.423478Z","caller":"traceutil/trace.go:171","msg":"trace[303298539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"1.125390344s","start":"2026-02-24T04:31:08.298052Z","end":"2026-02-24T04:31:09.423443Z","steps":["trace[303298539] 'agreement among raft nodes before linearized reading' (duration: 975.34771ms)","trace[303298539] 'range keys from in-memory index tree' (duration: 149.893578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:09.423524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.298045Z","time spent":"1.125468107s","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T04:31:09.424994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.157439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:31:09.425204Z","caller":"traceutil/trace.go:171","msg":"trace[756506511] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"806.318191ms","start":"2026-02-24T04:31:08.618859Z","end":"2026-02-24T04:31:09.425178Z","steps":["trace[756506511] 'process raft request' (duration: 654.627936ms)","trace[756506511] 'compare' (duration: 148.953262ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:09.425283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.618849Z","time spent":"806.400875ms","remote":"127.0.0.1:48364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:31:09.42691Z","caller":"traceutil/trace.go:171","msg":"trace[480616771] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"716.025527ms","start":"2026-02-24T04:31:08.710865Z","end":"2026-02-24T04:31:09.426891Z","steps":["trace[480616771] 'process raft request' (duration: 715.396223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.427017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.710842Z","time spent":"716.116091ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:31:09.427912Z","caller":"traceutil/trace.go:171","msg":"trace[1254331905] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"151.109854ms","start":"2026-02-24T04:31:09.27679Z","end":"2026-02-24T04:31:09.4279Z","steps":["trace[1254331905] 'process raft request' (duration: 151.060912ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:09.427922Z","caller":"traceutil/trace.go:171","msg":"trace[107384950] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"704.250078ms","start":"2026-02-24T04:31:08.723639Z","end":"2026-02-24T04:31:09.427889Z","steps":["trace[107384950] 'process raft request' (duration: 703.24712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.42806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.723624Z","time spent":"704.356641ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:31:09.428115Z","caller":"traceutil/trace.go:171","msg":"trace[384695537] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:649; }","duration":"154.923277ms","start":"2026-02-24T04:31:09.273169Z","end":"2026-02-24T04:31:09.428092Z","steps":["trace[384695537] 'read index received' (duration: 222.438µs)","trace[384695537] 'applied index is now lower than readState.Index' (duration: 154.697549ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:09.428321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.473575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"warn","ts":"2026-02-24T04:31:09.428336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.796686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bl5r2\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-24T04:31:09.428353Z","caller":"traceutil/trace.go:171","msg":"trace[445287766] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:636; }","duration":"295.543107ms","start":"2026-02-24T04:31:09.132802Z","end":"2026-02-24T04:31:09.428345Z","steps":["trace[445287766] 'agreement among raft nodes before linearized reading' (duration: 295.36384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.42834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.449672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:09.428444Z","caller":"traceutil/trace.go:171","msg":"trace[716691725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"153.578867ms","start":"2026-02-24T04:31:09.274853Z","end":"2026-02-24T04:31:09.428432Z","steps":["trace[716691725] 'agreement among raft nodes before linearized reading' (duration: 153.443702ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:09.428373Z","caller":"traceutil/trace.go:171","msg":"trace[2034227891] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bl5r2; range_end:; response_count:1; response_revision:636; }","duration":"308.875779ms","start":"2026-02-24T04:31:09.119486Z","end":"2026-02-24T04:31:09.428362Z","steps":["trace[2034227891] 'agreement among raft nodes before linearized reading' (duration: 308.740744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.428583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:09.119471Z","time spent":"309.102917ms","remote":"127.0.0.1:48452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17940,"request content":"key:\"/registry/pods/kube-system/cilium-bl5r2\" "} {"level":"warn","ts":"2026-02-24T04:31:09.422865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125235738s","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-24T04:31:09.434191Z","caller":"traceutil/trace.go:171","msg":"trace[27398792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.136660909s","start":"2026-02-24T04:31:08.297494Z","end":"2026-02-24T04:31:09.434155Z","steps":["trace[27398792] 'agreement among raft nodes before linearized reading' (duration: 975.950443ms)","trace[27398792] 'range keys from in-memory index tree' (duration: 149.14215ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:09.43426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:08.297486Z","time spent":"1.136751772s","remote":"127.0.0.1:48534","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-24T04:31:09.873471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.948949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:31:09.875002Z","caller":"traceutil/trace.go:171","msg":"trace[948133895] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"445.873782ms","start":"2026-02-24T04:31:09.429093Z","end":"2026-02-24T04:31:09.874967Z","steps":["trace[948133895] 'process raft request' (duration: 207.333663ms)","trace[948133895] 'compare' (duration: 236.798154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:09.875166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:09.429077Z","time spent":"446.007218ms","remote":"127.0.0.1:48534","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-24T04:31:09.902673Z","caller":"traceutil/trace.go:171","msg":"trace[811434986] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"463.831449ms","start":"2026-02-24T04:31:09.438823Z","end":"2026-02-24T04:31:09.902654Z","steps":["trace[811434986] 'process raft request' (duration: 463.780607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.902777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:09.438806Z","time spent":"463.925012ms","remote":"127.0.0.1:48364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:31:09.902769Z","caller":"traceutil/trace.go:171","msg":"trace[669756438] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"471.956555ms","start":"2026-02-24T04:31:09.430723Z","end":"2026-02-24T04:31:09.90268Z","steps":["trace[669756438] 'process raft request' (duration: 471.758357ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:09.90294Z","caller":"traceutil/trace.go:171","msg":"trace[1709139337] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"462.781959ms","start":"2026-02-24T04:31:09.440132Z","end":"2026-02-24T04:31:09.902914Z","steps":["trace[1709139337] 'process raft request' (duration: 462.498849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.903853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:09.430699Z","time spent":"473.093897ms","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:31:09.903947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:09.440117Z","time spent":"463.763286ms","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:31:09.903219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.30623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:09.91006Z","caller":"traceutil/trace.go:171","msg":"trace[1724008729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"465.132508ms","start":"2026-02-24T04:31:09.444859Z","end":"2026-02-24T04:31:09.909991Z","steps":["trace[1724008729] 'agreement among raft nodes before linearized reading' (duration: 458.315701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.910154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:09.444846Z","time spent":"465.274103ms","remote":"127.0.0.1:48276","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-24T04:31:09.903258Z","caller":"traceutil/trace.go:171","msg":"trace[1219013192] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"378.488143ms","start":"2026-02-24T04:31:09.524759Z","end":"2026-02-24T04:31:09.903247Z","steps":["trace[1219013192] 'process raft request' (duration: 378.162281ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:09.903282Z","caller":"traceutil/trace.go:171","msg":"trace[529131508] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"459.876819ms","start":"2026-02-24T04:31:09.443381Z","end":"2026-02-24T04:31:09.903258Z","steps":["trace[529131508] 'process raft request' (duration: 459.412552ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:09.903098Z","caller":"traceutil/trace.go:171","msg":"trace[947616244] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:654; }","duration":"458.158955ms","start":"2026-02-24T04:31:09.444895Z","end":"2026-02-24T04:31:09.903054Z","steps":["trace[947616244] 'read index received' (duration: 191.61528ms)","trace[947616244] 'applied index is now lower than readState.Index' (duration: 266.542745ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:31:09.91072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:09.443364Z","time spent":"467.284009ms","remote":"127.0.0.1:48452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18065,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:31:09.910886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.252086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-24T04:31:09.910915Z","caller":"traceutil/trace.go:171","msg":"trace[2139183131] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:642; }","duration":"390.334799ms","start":"2026-02-24T04:31:09.520572Z","end":"2026-02-24T04:31:09.910906Z","steps":["trace[2139183131] 'agreement among raft nodes before linearized reading' (duration: 390.194404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.910933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:09.520555Z","time spent":"390.37437ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188274,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-24T04:31:09.911013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.874119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-24T04:31:09.911082Z","caller":"traceutil/trace.go:171","msg":"trace[161496253] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:642; }","duration":"278.985733ms","start":"2026-02-24T04:31:09.632075Z","end":"2026-02-24T04:31:09.91106Z","steps":["trace[161496253] 'agreement among raft nodes before linearized reading' (duration: 278.828257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:09.911394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:09.52473Z","time spent":"385.771647ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:31:10.293564Z","caller":"traceutil/trace.go:171","msg":"trace[967648006] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"161.809714ms","start":"2026-02-24T04:31:10.13173Z","end":"2026-02-24T04:31:10.29354Z","steps":["trace[967648006] 'process raft request' (duration: 161.69121ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:10.478012Z","caller":"traceutil/trace.go:171","msg":"trace[1840280066] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"335.047764ms","start":"2026-02-24T04:31:10.142943Z","end":"2026-02-24T04:31:10.47799Z","steps":["trace[1840280066] 'process raft request' (duration: 330.181843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:10.142923Z","time spent":"335.160948ms","remote":"127.0.0.1:48452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18060,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:31:10.478292Z","caller":"traceutil/trace.go:171","msg":"trace[2056349606] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"329.896402ms","start":"2026-02-24T04:31:10.148386Z","end":"2026-02-24T04:31:10.478282Z","steps":["trace[2056349606] 'read index received' (duration: 145.066921ms)","trace[2056349606] 'applied index is now lower than readState.Index' (duration: 184.828641ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:31:10.478398Z","caller":"traceutil/trace.go:171","msg":"trace[1194965320] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"233.14968ms","start":"2026-02-24T04:31:10.245221Z","end":"2026-02-24T04:31:10.47837Z","steps":["trace[1194965320] 'process raft request' (duration: 232.722834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.065738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:31:10.478463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.020336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:10.47849Z","caller":"traceutil/trace.go:171","msg":"trace[1061572128] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:645; }","duration":"330.1212ms","start":"2026-02-24T04:31:10.14836Z","end":"2026-02-24T04:31:10.478481Z","steps":["trace[1061572128] 'agreement among raft nodes before linearized reading' (duration: 330.022087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:10.14835Z","time spent":"330.162852ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-24T04:31:10.478519Z","caller":"traceutil/trace.go:171","msg":"trace[1995392614] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:645; }","duration":"330.083569ms","start":"2026-02-24T04:31:10.148414Z","end":"2026-02-24T04:31:10.478498Z","steps":["trace[1995392614] 'agreement among raft nodes before linearized reading' (duration: 329.996486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:10.148409Z","time spent":"330.137061ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-24T04:31:10.478567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.945246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:10.478589Z","caller":"traceutil/trace.go:171","msg":"trace[650805364] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:645; }","duration":"156.009558ms","start":"2026-02-24T04:31:10.322572Z","end":"2026-02-24T04:31:10.478582Z","steps":["trace[650805364] 'agreement among raft nodes before linearized reading' (duration: 155.949096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.183466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:31:10.478646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.445546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:31:10.478651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.08938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:10.478674Z","caller":"traceutil/trace.go:171","msg":"trace[1076723177] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:645; }","duration":"307.503978ms","start":"2026-02-24T04:31:10.171162Z","end":"2026-02-24T04:31:10.478665Z","steps":["trace[1076723177] 'agreement among raft nodes before linearized reading' (duration: 307.462067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.036149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:31:10.478702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:10.171147Z","time spent":"307.548829ms","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-02-24T04:31:10.478715Z","caller":"traceutil/trace.go:171","msg":"trace[1426946748] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:645; }","duration":"196.151223ms","start":"2026-02-24T04:31:10.282539Z","end":"2026-02-24T04:31:10.47869Z","steps":["trace[1426946748] 'agreement among raft nodes before linearized reading' (duration: 196.07787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.133473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:31:10.478727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.000605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:10.478751Z","caller":"traceutil/trace.go:171","msg":"trace[49380357] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:645; }","duration":"156.174704ms","start":"2026-02-24T04:31:10.322572Z","end":"2026-02-24T04:31:10.478747Z","steps":["trace[49380357] 'agreement among raft nodes before linearized reading' (duration: 156.149843ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:10.478763Z","caller":"traceutil/trace.go:171","msg":"trace[840643817] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:645; }","duration":"229.050587ms","start":"2026-02-24T04:31:10.249702Z","end":"2026-02-24T04:31:10.478753Z","steps":["trace[840643817] 'agreement among raft nodes before linearized reading' (duration: 228.995885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.064138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:10.478712Z","caller":"traceutil/trace.go:171","msg":"trace[312947408] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:645; }","duration":"156.0552ms","start":"2026-02-24T04:31:10.322652Z","end":"2026-02-24T04:31:10.478708Z","steps":["trace[312947408] 'agreement among raft nodes before linearized reading' (duration: 156.025999ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:10.478821Z","caller":"traceutil/trace.go:171","msg":"trace[742232019] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:645; }","duration":"229.12972ms","start":"2026-02-24T04:31:10.249676Z","end":"2026-02-24T04:31:10.478806Z","steps":["trace[742232019] 'agreement among raft nodes before linearized reading' (duration: 229.062008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.836561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:31:10.478565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.020609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:10.478879Z","caller":"traceutil/trace.go:171","msg":"trace[464645039] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:645; }","duration":"307.865542ms","start":"2026-02-24T04:31:10.171005Z","end":"2026-02-24T04:31:10.478871Z","steps":["trace[464645039] 'agreement among raft nodes before linearized reading' (duration: 307.82352ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:10.478899Z","caller":"traceutil/trace.go:171","msg":"trace[127779989] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:645; }","duration":"228.412204ms","start":"2026-02-24T04:31:10.25048Z","end":"2026-02-24T04:31:10.478893Z","steps":["trace[127779989] 'agreement among raft nodes before linearized reading' (duration: 227.986358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:10.17097Z","time spent":"307.929484ms","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"warn","ts":"2026-02-24T04:31:10.478921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.468943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:10.478947Z","caller":"traceutil/trace.go:171","msg":"trace[483208020] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:645; }","duration":"330.534255ms","start":"2026-02-24T04:31:10.148404Z","end":"2026-02-24T04:31:10.478938Z","steps":["trace[483208020] 'agreement among raft nodes before linearized reading' (duration: 330.496394ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:10.478654Z","caller":"traceutil/trace.go:171","msg":"trace[1672076038] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:645; }","duration":"307.299911ms","start":"2026-02-24T04:31:10.171331Z","end":"2026-02-24T04:31:10.478631Z","steps":["trace[1672076038] 'agreement among raft nodes before linearized reading' (duration: 307.147585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.478975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:10.148388Z","time spent":"330.578047ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"warn","ts":"2026-02-24T04:31:10.479011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:31:10.171327Z","time spent":"307.667654ms","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-02-24T04:31:10.479008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.060506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:10.47906Z","caller":"traceutil/trace.go:171","msg":"trace[983296134] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:645; }","duration":"197.125049ms","start":"2026-02-24T04:31:10.28192Z","end":"2026-02-24T04:31:10.479045Z","steps":["trace[983296134] 'agreement among raft nodes before linearized reading' (duration: 197.043976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:31:10.479104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.249554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:31:10.479159Z","caller":"traceutil/trace.go:171","msg":"trace[1820298865] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:645; }","duration":"197.303726ms","start":"2026-02-24T04:31:10.281836Z","end":"2026-02-24T04:31:10.47914Z","steps":["trace[1820298865] 'agreement among raft nodes before linearized reading' (duration: 197.219823ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:11.310711Z","caller":"traceutil/trace.go:171","msg":"trace[612423846] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"123.258204ms","start":"2026-02-24T04:31:11.187411Z","end":"2026-02-24T04:31:11.310669Z","steps":["trace[612423846] 'process raft request' (duration: 122.838368ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:18.394989Z","caller":"traceutil/trace.go:171","msg":"trace[2041932227] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"132.166953ms","start":"2026-02-24T04:31:18.262803Z","end":"2026-02-24T04:31:18.39497Z","steps":["trace[2041932227] 'process raft request' (duration: 130.941192ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:18.402575Z","caller":"traceutil/trace.go:171","msg":"trace[267963755] transaction","detail":"{read_only:false; number_of_response:1; response_revision:738; }","duration":"137.437361ms","start":"2026-02-24T04:31:18.265117Z","end":"2026-02-24T04:31:18.402554Z","steps":["trace[267963755] 'process raft request' (duration: 137.332657ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:25.500696Z","caller":"traceutil/trace.go:171","msg":"trace[1279945580] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"106.330389ms","start":"2026-02-24T04:31:25.394332Z","end":"2026-02-24T04:31:25.500662Z","steps":["trace[1279945580] 'process raft request' (duration: 106.165134ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:31:46.401587Z","caller":"traceutil/trace.go:171","msg":"trace[686027164] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"120.894134ms","start":"2026-02-24T04:31:46.280658Z","end":"2026-02-24T04:31:46.401552Z","steps":["trace[686027164] 'process raft request' (duration: 120.425853ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:20.158163Z","caller":"traceutil/trace.go:171","msg":"trace[1513084864] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"177.955519ms","start":"2026-02-24T04:32:19.98018Z","end":"2026-02-24T04:32:20.158136Z","steps":["trace[1513084864] 'read index received' (duration: 177.839207ms)","trace[1513084864] 'applied index is now lower than readState.Index' (duration: 113.572µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:32:20.158262Z","caller":"traceutil/trace.go:171","msg":"trace[726499514] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"183.722345ms","start":"2026-02-24T04:32:19.974502Z","end":"2026-02-24T04:32:20.158224Z","steps":["trace[726499514] 'process raft request' (duration: 183.47719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:20.15851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.314315ms","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-24T04:32:20.158564Z","caller":"traceutil/trace.go:171","msg":"trace[1098167320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"178.405917ms","start":"2026-02-24T04:32:19.980142Z","end":"2026-02-24T04:32:20.158548Z","steps":["trace[1098167320] 'agreement among raft nodes before linearized reading' (duration: 178.124252ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:28.385313Z","caller":"traceutil/trace.go:171","msg":"trace[250963192] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"190.187022ms","start":"2026-02-24T04:32:28.195099Z","end":"2026-02-24T04:32:28.385286Z","steps":["trace[250963192] 'process raft request' (duration: 189.989639ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:28.385414Z","caller":"traceutil/trace.go:171","msg":"trace[1034360619] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"161.997104ms","start":"2026-02-24T04:32:28.223386Z","end":"2026-02-24T04:32:28.385383Z","steps":["trace[1034360619] 'read index received' (duration: 161.77742ms)","trace[1034360619] 'applied index is now lower than readState.Index' (duration: 216.374µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:32:28.38569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.283539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T04:32:28.385774Z","caller":"traceutil/trace.go:171","msg":"trace[1301672701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"162.42008ms","start":"2026-02-24T04:32:28.223334Z","end":"2026-02-24T04:32:28.385754Z","steps":["trace[1301672701] 'agreement among raft nodes before linearized reading' (duration: 162.161936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:28.806256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.857742ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T04:32:28.806534Z","caller":"traceutil/trace.go:171","msg":"trace[251332626] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1023; }","duration":"413.765653ms","start":"2026-02-24T04:32:28.392751Z","end":"2026-02-24T04:32:28.806517Z","steps":["trace[251332626] 'read index received' (duration: 158.616916ms)","trace[251332626] 'applied index is now lower than readState.Index' (duration: 255.147897ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:32:28.806857Z","caller":"traceutil/trace.go:171","msg":"trace[1797091401] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"417.1118ms","start":"2026-02-24T04:32:28.389734Z","end":"2026-02-24T04:32:28.806846Z","steps":["trace[1797091401] 'process raft request' (duration: 416.632312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:28.806954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:28.389709Z","time spent":"417.183032ms","remote":"127.0.0.1:48534","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-24T04:32:28.807188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.432954ms","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-24T04:32:28.807222Z","caller":"traceutil/trace.go:171","msg":"trace[1435623484] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:986; }","duration":"414.496785ms","start":"2026-02-24T04:32:28.392717Z","end":"2026-02-24T04:32:28.807213Z","steps":["trace[1435623484] 'agreement among raft nodes before linearized reading' (duration: 414.388893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:28.80725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:28.392679Z","time spent":"414.564057ms","remote":"127.0.0.1:48364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":121,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:32:28.807457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.531994ms","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-24T04:32:28.807495Z","caller":"traceutil/trace.go:171","msg":"trace[169647840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"205.602745ms","start":"2026-02-24T04:32:28.601883Z","end":"2026-02-24T04:32:28.807485Z","steps":["trace[169647840] 'agreement among raft nodes before linearized reading' (duration: 205.499723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:28.807836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.616565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:32:28.807898Z","caller":"traceutil/trace.go:171","msg":"trace[1319182982] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:986; }","duration":"115.720146ms","start":"2026-02-24T04:32:28.692164Z","end":"2026-02-24T04:32:28.807884Z","steps":["trace[1319182982] 'agreement among raft nodes before linearized reading' (duration: 115.636605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:28.808142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.390713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T04:32:28.808178Z","caller":"traceutil/trace.go:171","msg":"trace[1687558112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"191.460994ms","start":"2026-02-24T04:32:28.616708Z","end":"2026-02-24T04:32:28.808169Z","steps":["trace[1687558112] 'agreement among raft nodes before linearized reading' (duration: 191.368483ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:28.929533Z","caller":"traceutil/trace.go:171","msg":"trace[700481575] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"117.438846ms","start":"2026-02-24T04:32:28.812065Z","end":"2026-02-24T04:32:28.929504Z","steps":["trace[700481575] 'process raft request' (duration: 60.092421ms)","trace[700481575] 'compare' (duration: 57.139621ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:32:28.967451Z","caller":"traceutil/trace.go:171","msg":"trace[1101448146] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"153.994157ms","start":"2026-02-24T04:32:28.813426Z","end":"2026-02-24T04:32:28.96742Z","steps":["trace[1101448146] 'process raft request' (duration: 153.53946ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:28.968205Z","caller":"traceutil/trace.go:171","msg":"trace[790544534] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"154.686089ms","start":"2026-02-24T04:32:28.813491Z","end":"2026-02-24T04:32:28.968177Z","steps":["trace[790544534] 'process raft request' (duration: 153.57228ms)"],"step_count":1}