{"level":"warn","ts":"2026-02-09T18:49:08.904897Z","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-09T18:49:08.905013Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-09T18:49:08.905214Z","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-09T18:49:08.905291Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-09T18:49:08.905384Z","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-09T18:49:08.906279Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-02-09T18:49:08.91253Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-09T18:49:08.916997Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.10547ms"} {"level":"info","ts":"2026-02-09T18:49:08.955979Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-02-09T18:49:08.956142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T18:49:08.956165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-02-09T18:49:08.956308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T18:49:08.956323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-02-09T18:49:08.956453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-02-09T18:49:08.965389Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T18:49:08.969892Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T18:49:08.973937Z","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-09T18:49:08.977958Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T18:49:08.999818Z","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-09T18:49:09.000112Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T18:49:09.000145Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T18:49:09.000103Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T18:49:09.000357Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-09T18:49:09.000372Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-09T18:49:09.000737Z","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-09T18:49:09.000773Z","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-09T18:49:09.000833Z","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-09T18:49:09.004054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-02-09T18:49:09.004157Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-09T18:49:09.357117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T18:49:09.357249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T18:49:09.357307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-02-09T18:49:09.357351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-02-09T18:49:09.357368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-09T18:49:09.357386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-02-09T18:49:09.357431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-09T18:49:09.420527Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T18:49:09.420549Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:49:09.42094Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T18:49:09.421302Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T18:49:09.4214Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T18:49:09.421441Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T18:49:09.423026Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T18:49:09.428676Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-02-09T18:49:09.429837Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:49:09.430239Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:49:09.430883Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:49:12.967822Z","caller":"traceutil/trace.go:171","msg":"trace[1256103214] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"151.262261ms","start":"2026-02-09T18:49:12.816501Z","end":"2026-02-09T18:49:12.967763Z","steps":["trace[1256103214] 'process raft request' (duration: 150.884942ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:13.1917Z","caller":"traceutil/trace.go:171","msg":"trace[1897594910] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"216.244222ms","start":"2026-02-09T18:49:12.975431Z","end":"2026-02-09T18:49:13.191675Z","steps":["trace[1897594910] 'process raft request' (duration: 211.207744ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:22.169411Z","caller":"traceutil/trace.go:171","msg":"trace[1745718484] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"165.141186ms","start":"2026-02-09T18:49:22.004243Z","end":"2026-02-09T18:49:22.169384Z","steps":["trace[1745718484] 'process raft request' (duration: 95.662961ms)","trace[1745718484] 'compare' (duration: 69.342084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:49:25.694682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338616313012058,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T18:49:25.831293Z","caller":"traceutil/trace.go:171","msg":"trace[180764241] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"750.549941ms","start":"2026-02-09T18:49:25.080722Z","end":"2026-02-09T18:49:25.831272Z","steps":["trace[180764241] 'process raft request' (duration: 750.430331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:25.83192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:25.080704Z","time spent":"750.653419ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:49:25.832312Z","caller":"traceutil/trace.go:171","msg":"trace[1755639401] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:385; }","duration":"638.585583ms","start":"2026-02-09T18:49:25.193713Z","end":"2026-02-09T18:49:25.832299Z","steps":["trace[1755639401] 'read index received' (duration: 638.576863ms)","trace[1755639401] 'applied index is now lower than readState.Index' (duration: 7.6µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:49:25.832409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.689221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-09T18:49:25.832447Z","caller":"traceutil/trace.go:171","msg":"trace[1205808700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"638.763658ms","start":"2026-02-09T18:49:25.193675Z","end":"2026-02-09T18:49:25.832439Z","steps":["trace[1205808700] 'agreement among raft nodes before linearized reading' (duration: 638.697382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:25.832475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:25.193655Z","time spent":"638.813411ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T18:49:25.83261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.31944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T18:49:25.832637Z","caller":"traceutil/trace.go:171","msg":"trace[490392686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:373; }","duration":"509.364763ms","start":"2026-02-09T18:49:25.323263Z","end":"2026-02-09T18:49:25.832627Z","steps":["trace[490392686] 'agreement among raft nodes before linearized reading' (duration: 509.32469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:25.832663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:25.323249Z","time spent":"509.408177ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:49:25.832817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.755037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:49:25.832844Z","caller":"traceutil/trace.go:171","msg":"trace[1360940072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:373; }","duration":"315.816942ms","start":"2026-02-09T18:49:25.517018Z","end":"2026-02-09T18:49:25.832835Z","steps":["trace[1360940072] 'agreement among raft nodes before linearized reading' (duration: 315.780269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:25.83288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:25.517Z","time spent":"315.871066ms","remote":"127.0.0.1:43398","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-09T18:49:25.833013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.799612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T18:49:25.833041Z","caller":"traceutil/trace.go:171","msg":"trace[1926853944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:373; }","duration":"383.852977ms","start":"2026-02-09T18:49:25.44918Z","end":"2026-02-09T18:49:25.833033Z","steps":["trace[1926853944] 'agreement among raft nodes before linearized reading' (duration: 383.807504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:25.833067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:25.449167Z","time spent":"383.89292ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:49:25.83318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.44379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-09T18:49:25.833204Z","caller":"traceutil/trace.go:171","msg":"trace[1973765386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:373; }","duration":"509.476793ms","start":"2026-02-09T18:49:25.32372Z","end":"2026-02-09T18:49:25.833197Z","steps":["trace[1973765386] 'agreement among raft nodes before linearized reading' (duration: 509.43807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:25.833228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:25.323716Z","time spent":"509.507355ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-09T18:49:26.065869Z","caller":"traceutil/trace.go:171","msg":"trace[1896810508] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:385; }","duration":"210.247498ms","start":"2026-02-09T18:49:25.855601Z","end":"2026-02-09T18:49:26.065848Z","steps":["trace[1896810508] 'read index received' (duration: 207.690108ms)","trace[1896810508] 'applied index is now lower than readState.Index' (duration: 2.55664ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:49:26.065994Z","caller":"traceutil/trace.go:171","msg":"trace[575414073] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"219.807299ms","start":"2026-02-09T18:49:25.846168Z","end":"2026-02-09T18:49:26.065975Z","steps":["trace[575414073] 'process raft request' (duration: 219.580011ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:26.066418Z","caller":"traceutil/trace.go:171","msg":"trace[1143535210] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"217.61756ms","start":"2026-02-09T18:49:25.848786Z","end":"2026-02-09T18:49:26.066404Z","steps":["trace[1143535210] 'process raft request' (duration: 217.033042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:26.066578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.958896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-09T18:49:26.066609Z","caller":"traceutil/trace.go:171","msg":"trace[1718111490] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:377; }","duration":"211.003459ms","start":"2026-02-09T18:49:25.855597Z","end":"2026-02-09T18:49:26.0666Z","steps":["trace[1718111490] 'agreement among raft nodes before linearized reading' (duration: 210.928273ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:26.066037Z","caller":"traceutil/trace.go:171","msg":"trace[1365042930] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"220.032618ms","start":"2026-02-09T18:49:25.845999Z","end":"2026-02-09T18:49:26.066032Z","steps":["trace[1365042930] 'process raft request' (duration: 217.279902ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:26.067091Z","caller":"traceutil/trace.go:171","msg":"trace[666926092] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"219.845502ms","start":"2026-02-09T18:49:25.846214Z","end":"2026-02-09T18:49:26.06606Z","steps":["trace[666926092] 'process raft request' (duration: 219.57257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:34.671812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.691075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:49:34.67197Z","caller":"traceutil/trace.go:171","msg":"trace[1652419737] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"485.59444ms","start":"2026-02-09T18:49:34.186364Z","end":"2026-02-09T18:49:34.671959Z","steps":["trace[1652419737] 'process raft request' (duration: 485.571028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:34.67202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:34.18636Z","time spent":"485.639634ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:49:34.672343Z","caller":"traceutil/trace.go:171","msg":"trace[1557230830] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"486.111953ms","start":"2026-02-09T18:49:34.186196Z","end":"2026-02-09T18:49:34.672308Z","steps":["trace[1557230830] 'process raft request' (duration: 85.759524ms)","trace[1557230830] 'compare' (duration: 399.612068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:49:34.672514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:34.18618Z","time spent":"486.260775ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:49:34.672914Z","caller":"traceutil/trace.go:171","msg":"trace[191222980] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"486.572901ms","start":"2026-02-09T18:49:34.18633Z","end":"2026-02-09T18:49:34.672903Z","steps":["trace[191222980] 'process raft request' (duration: 485.569947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:34.672983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:34.186324Z","time spent":"486.615995ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:49:40.961445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.014217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T18:49:40.961533Z","caller":"traceutil/trace.go:171","msg":"trace[1652355511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:471; }","duration":"145.147669ms","start":"2026-02-09T18:49:40.816365Z","end":"2026-02-09T18:49:40.961513Z","steps":["trace[1652355511] 'range keys from in-memory index tree' (duration: 144.92047ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:42.286772Z","caller":"traceutil/trace.go:171","msg":"trace[767773745] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"135.214742ms","start":"2026-02-09T18:49:42.151543Z","end":"2026-02-09T18:49:42.286758Z","steps":["trace[767773745] 'process raft request' (duration: 127.127604ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:42.481025Z","caller":"traceutil/trace.go:171","msg":"trace[75699798] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"100.845112ms","start":"2026-02-09T18:49:42.380155Z","end":"2026-02-09T18:49:42.481Z","steps":["trace[75699798] 'process raft request' (duration: 100.717129ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:42.654526Z","caller":"traceutil/trace.go:171","msg":"trace[581174808] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"144.855133ms","start":"2026-02-09T18:49:42.509658Z","end":"2026-02-09T18:49:42.654513Z","steps":["trace[581174808] 'process raft request' (duration: 144.779257ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:42.654754Z","caller":"traceutil/trace.go:171","msg":"trace[943897524] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:495; }","duration":"144.537031ms","start":"2026-02-09T18:49:42.510192Z","end":"2026-02-09T18:49:42.654729Z","steps":["trace[943897524] 'read index received' (duration: 144.528451ms)","trace[943897524] 'applied index is now lower than readState.Index' (duration: 6.7µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:49:42.654957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.72405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:49:42.654994Z","caller":"traceutil/trace.go:171","msg":"trace[1016903531] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:479; }","duration":"144.818525ms","start":"2026-02-09T18:49:42.510162Z","end":"2026-02-09T18:49:42.654981Z","steps":["trace[1016903531] 'agreement among raft nodes before linearized reading' (duration: 144.688802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:42.684051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.167296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:49:42.684538Z","caller":"traceutil/trace.go:171","msg":"trace[1098730145] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"163.542768ms","start":"2026-02-09T18:49:42.520985Z","end":"2026-02-09T18:49:42.684528Z","steps":["trace[1098730145] 'process raft request' (duration: 162.909034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:42.68542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.208828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:49:42.685449Z","caller":"traceutil/trace.go:171","msg":"trace[1890092817] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:481; }","duration":"168.248445ms","start":"2026-02-09T18:49:42.517194Z","end":"2026-02-09T18:49:42.685442Z","steps":["trace[1890092817] 'agreement among raft nodes before linearized reading' (duration: 168.196268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:42.685706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.515901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:49:42.685728Z","caller":"traceutil/trace.go:171","msg":"trace[668594897] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:481; }","duration":"168.551088ms","start":"2026-02-09T18:49:42.517171Z","end":"2026-02-09T18:49:42.685722Z","steps":["trace[668594897] 'agreement among raft nodes before linearized reading' (duration: 168.51978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:42.685845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.894317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:49:42.685864Z","caller":"traceutil/trace.go:171","msg":"trace[1158193775] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:481; }","duration":"170.922916ms","start":"2026-02-09T18:49:42.514935Z","end":"2026-02-09T18:49:42.685858Z","steps":["trace[1158193775] 'agreement among raft nodes before linearized reading' (duration: 170.895287ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:42.684122Z","caller":"traceutil/trace.go:171","msg":"trace[1210882021] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"173.525781ms","start":"2026-02-09T18:49:42.510585Z","end":"2026-02-09T18:49:42.684111Z","steps":["trace[1210882021] 'process raft request' (duration: 173.219238ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:42.684538Z","caller":"traceutil/trace.go:171","msg":"trace[29325244] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:481; }","duration":"172.660748ms","start":"2026-02-09T18:49:42.511851Z","end":"2026-02-09T18:49:42.684512Z","steps":["trace[29325244] 'agreement among raft nodes before linearized reading' (duration: 172.129508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:42.843061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.562111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:49:42.843156Z","caller":"traceutil/trace.go:171","msg":"trace[1716133438] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"147.522413ms","start":"2026-02-09T18:49:42.695611Z","end":"2026-02-09T18:49:42.843133Z","steps":["trace[1716133438] 'process raft request' (duration: 38.821829ms)","trace[1716133438] 'compare' (duration: 108.403161ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:49:42.844857Z","caller":"traceutil/trace.go:171","msg":"trace[809475815] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"144.563979ms","start":"2026-02-09T18:49:42.700283Z","end":"2026-02-09T18:49:42.844847Z","steps":["trace[809475815] 'process raft request' (duration: 144.466884ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:42.845011Z","caller":"traceutil/trace.go:171","msg":"trace[1119452017] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"144.672893ms","start":"2026-02-09T18:49:42.700323Z","end":"2026-02-09T18:49:42.844996Z","steps":["trace[1119452017] 'process raft request' (duration: 144.498742ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:42.845222Z","caller":"traceutil/trace.go:171","msg":"trace[1365870700] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"143.342008ms","start":"2026-02-09T18:49:42.701872Z","end":"2026-02-09T18:49:42.845214Z","steps":["trace[1365870700] 'process raft request' (duration: 143.085882ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:42.845412Z","caller":"traceutil/trace.go:171","msg":"trace[998374126] transaction","detail":"{read_only:false; number_of_response:0; response_revision:487; }","duration":"143.433722ms","start":"2026-02-09T18:49:42.701972Z","end":"2026-02-09T18:49:42.845405Z","steps":["trace[998374126] 'process raft request' (duration: 143.200685ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:43.769642Z","caller":"traceutil/trace.go:171","msg":"trace[235523911] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"133.576529ms","start":"2026-02-09T18:49:43.636042Z","end":"2026-02-09T18:49:43.769618Z","steps":["trace[235523911] 'process raft request' (duration: 133.273015ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:43.769693Z","caller":"traceutil/trace.go:171","msg":"trace[1693391753] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"133.56552ms","start":"2026-02-09T18:49:43.636106Z","end":"2026-02-09T18:49:43.769671Z","steps":["trace[1693391753] 'process raft request' (duration: 133.287695ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:44.031064Z","caller":"traceutil/trace.go:171","msg":"trace[690392087] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"140.019815ms","start":"2026-02-09T18:49:43.891035Z","end":"2026-02-09T18:49:44.031055Z","steps":["trace[690392087] 'process raft request' (duration: 100.853723ms)","trace[690392087] 'compare' (duration: 38.876017ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:49:44.031028Z","caller":"traceutil/trace.go:171","msg":"trace[946895024] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"137.639983ms","start":"2026-02-09T18:49:43.893372Z","end":"2026-02-09T18:49:44.031012Z","steps":["trace[946895024] 'process raft request' (duration: 137.498561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:44.85437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338616313012313,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T18:49:45.265592Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.231139105s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T18:49:45.266335Z","caller":"traceutil/trace.go:171","msg":"trace[84656572] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.231923896s","start":"2026-02-09T18:49:44.03439Z","end":"2026-02-09T18:49:45.266314Z","steps":["trace[84656572] 'process raft request' (duration: 1.231795362s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.266454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.034373Z","time spent":"1.232011941s","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:49:45.48129Z","caller":"traceutil/trace.go:171","msg":"trace[2117023788] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:531; }","duration":"1.12758773s","start":"2026-02-09T18:49:44.353684Z","end":"2026-02-09T18:49:45.481271Z","steps":["trace[2117023788] 'read index received' (duration: 912.552567ms)","trace[2117023788] 'applied index is now lower than readState.Index' (duration: 215.034243ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:49:45.481682Z","caller":"traceutil/trace.go:171","msg":"trace[1250893635] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.446392339s","start":"2026-02-09T18:49:44.035277Z","end":"2026-02-09T18:49:45.481669Z","steps":["trace[1250893635] 'process raft request' (duration: 1.445212099s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.481886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.035262Z","time spent":"1.446542312s","remote":"127.0.0.1:43802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1066,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T18:49:45.482589Z","caller":"traceutil/trace.go:171","msg":"trace[1854527014] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.391029827s","start":"2026-02-09T18:49:44.091547Z","end":"2026-02-09T18:49:45.482576Z","steps":["trace[1854527014] 'process raft request' (duration: 1.389225517s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.482778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.091533Z","time spent":"1.391141622s","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T18:49:45.48303Z","caller":"traceutil/trace.go:171","msg":"trace[960389350] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.19254272s","start":"2026-02-09T18:49:44.290476Z","end":"2026-02-09T18:49:45.483019Z","steps":["trace[960389350] 'process raft request' (duration: 1.19033567s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.483293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.290451Z","time spent":"1.192806527s","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T18:49:45.483561Z","caller":"traceutil/trace.go:171","msg":"trace[203109251] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"992.472044ms","start":"2026-02-09T18:49:44.491078Z","end":"2026-02-09T18:49:45.48355Z","steps":["trace[203109251] 'process raft request' (duration: 989.76395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.483822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.491055Z","time spent":"992.688724ms","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T18:49:45.484072Z","caller":"traceutil/trace.go:171","msg":"trace[383030797] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"768.360298ms","start":"2026-02-09T18:49:44.715701Z","end":"2026-02-09T18:49:45.484061Z","steps":["trace[383030797] 'process raft request' (duration: 765.398416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.484245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.715689Z","time spent":"768.492882ms","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-09T18:49:45.484682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13098954s","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-09T18:49:45.484881Z","caller":"traceutil/trace.go:171","msg":"trace[226487830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"1.131211839s","start":"2026-02-09T18:49:44.353657Z","end":"2026-02-09T18:49:45.484869Z","steps":["trace[226487830] 'agreement among raft nodes before linearized reading' (duration: 1.130911364s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.485052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.353644Z","time spent":"1.131367681s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-09T18:49:45.523628Z","caller":"traceutil/trace.go:171","msg":"trace[2122588549] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"209.553707ms","start":"2026-02-09T18:49:45.314058Z","end":"2026-02-09T18:49:45.523612Z","steps":["trace[2122588549] 'process raft request' (duration: 209.417744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.524002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.610292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-09T18:49:45.52802Z","caller":"traceutil/trace.go:171","msg":"trace[1718921010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"369.663488ms","start":"2026-02-09T18:49:45.158346Z","end":"2026-02-09T18:49:45.528009Z","steps":["trace[1718921010] 'agreement among raft nodes before linearized reading' (duration: 365.600162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.528055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:45.158332Z","time spent":"369.712366ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:49:45.524041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.440857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-09T18:49:45.528169Z","caller":"traceutil/trace.go:171","msg":"trace[1621809067] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:522; }","duration":"440.607429ms","start":"2026-02-09T18:49:45.087553Z","end":"2026-02-09T18:49:45.52816Z","steps":["trace[1621809067] 'agreement among raft nodes before linearized reading' (duration: 436.471116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.528195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:45.087535Z","time spent":"440.653436ms","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-09T18:49:45.524068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.424828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-09T18:49:45.528323Z","caller":"traceutil/trace.go:171","msg":"trace[1432475713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"595.706453ms","start":"2026-02-09T18:49:44.93261Z","end":"2026-02-09T18:49:45.528317Z","steps":["trace[1432475713] 'agreement among raft nodes before linearized reading' (duration: 591.441896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.528347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.932595Z","time spent":"595.745921ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:49:45.524142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.213876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T18:49:45.52847Z","caller":"traceutil/trace.go:171","msg":"trace[1529602345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"612.627164ms","start":"2026-02-09T18:49:44.915837Z","end":"2026-02-09T18:49:45.528464Z","steps":["trace[1529602345] 'agreement among raft nodes before linearized reading' (duration: 608.243024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.528492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.915816Z","time spent":"612.669592ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:49:45.524167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.649618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-09T18:49:45.528571Z","caller":"traceutil/trace.go:171","msg":"trace[1324689260] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:522; }","duration":"641.078776ms","start":"2026-02-09T18:49:44.887486Z","end":"2026-02-09T18:49:45.528565Z","steps":["trace[1324689260] 'agreement among raft nodes before linearized reading' (duration: 636.666267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.528591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.887467Z","time spent":"641.117854ms","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-09T18:49:45.524193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.872309ms","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-09T18:49:45.528675Z","caller":"traceutil/trace.go:171","msg":"trace[1609356385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:522; }","duration":"824.386903ms","start":"2026-02-09T18:49:44.704283Z","end":"2026-02-09T18:49:45.52867Z","steps":["trace[1609356385] 'agreement among raft nodes before linearized reading' (duration: 819.894258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.528697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:44.704265Z","time spent":"824.426362ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-09T18:49:45.524222Z","caller":"traceutil/trace.go:171","msg":"trace[1566321188] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"252.146487ms","start":"2026-02-09T18:49:45.272068Z","end":"2026-02-09T18:49:45.524215Z","steps":["trace[1566321188] 'process raft request' (duration: 251.161666ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:45.525906Z","caller":"traceutil/trace.go:171","msg":"trace[1785216409] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"213.241333ms","start":"2026-02-09T18:49:45.312656Z","end":"2026-02-09T18:49:45.525897Z","steps":["trace[1785216409] 'process raft request' (duration: 210.647213ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:45.652601Z","caller":"traceutil/trace.go:171","msg":"trace[1713447108] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"155.049714ms","start":"2026-02-09T18:49:45.497534Z","end":"2026-02-09T18:49:45.652584Z","steps":["trace[1713447108] 'process raft request' (duration: 108.408367ms)","trace[1713447108] 'compare' (duration: 46.100784ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:49:45.65291Z","caller":"traceutil/trace.go:171","msg":"trace[1111839679] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"150.758429ms","start":"2026-02-09T18:49:45.502143Z","end":"2026-02-09T18:49:45.652902Z","steps":["trace[1111839679] 'process raft request' (duration: 149.999847ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:45.653151Z","caller":"traceutil/trace.go:171","msg":"trace[1307808023] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"148.884293ms","start":"2026-02-09T18:49:45.504259Z","end":"2026-02-09T18:49:45.653144Z","steps":["trace[1307808023] 'process raft request' (duration: 147.917171ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:45.653371Z","caller":"traceutil/trace.go:171","msg":"trace[444829157] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:540; }","duration":"129.450595ms","start":"2026-02-09T18:49:45.523913Z","end":"2026-02-09T18:49:45.653363Z","steps":["trace[444829157] 'read index received' (duration: 82.036397ms)","trace[444829157] 'applied index is now lower than readState.Index' (duration: 47.413388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:49:45.65349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.378237ms","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-09T18:49:45.65358Z","caller":"traceutil/trace.go:171","msg":"trace[488733071] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:529; }","duration":"160.495801ms","start":"2026-02-09T18:49:45.493076Z","end":"2026-02-09T18:49:45.653571Z","steps":["trace[488733071] 'agreement among raft nodes before linearized reading' (duration: 160.372458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.654056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.980157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:49:45.654175Z","caller":"traceutil/trace.go:171","msg":"trace[506235990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"136.119581ms","start":"2026-02-09T18:49:45.518048Z","end":"2026-02-09T18:49:45.654167Z","steps":["trace[506235990] 'agreement among raft nodes before linearized reading' (duration: 135.986897ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:45.969799Z","caller":"traceutil/trace.go:171","msg":"trace[18897491] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"296.979085ms","start":"2026-02-09T18:49:45.672802Z","end":"2026-02-09T18:49:45.969781Z","steps":["trace[18897491] 'process raft request' (duration: 296.947716ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:45.970032Z","caller":"traceutil/trace.go:171","msg":"trace[2027943087] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"308.216063ms","start":"2026-02-09T18:49:45.661808Z","end":"2026-02-09T18:49:45.970024Z","steps":["trace[2027943087] 'process raft request' (duration: 234.575126ms)","trace[2027943087] 'compare' (duration: 72.863886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:49:45.970104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:45.661793Z","time spent":"308.26142ms","remote":"127.0.0.1:43912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3048,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T18:49:45.970197Z","caller":"traceutil/trace.go:171","msg":"trace[446287225] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"307.081709ms","start":"2026-02-09T18:49:45.663109Z","end":"2026-02-09T18:49:45.97019Z","steps":["trace[446287225] 'read index received' (duration: 233.349268ms)","trace[446287225] 'applied index is now lower than readState.Index' (duration: 73.731681ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:49:45.970334Z","caller":"traceutil/trace.go:171","msg":"trace[726222977] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"301.475919ms","start":"2026-02-09T18:49:45.668851Z","end":"2026-02-09T18:49:45.970327Z","steps":["trace[726222977] 'process raft request' (duration: 300.768405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.970385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:45.668834Z","time spent":"301.520067ms","remote":"127.0.0.1:43528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12712,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:49:45.970529Z","caller":"traceutil/trace.go:171","msg":"trace[1027123138] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"298.433062ms","start":"2026-02-09T18:49:45.672089Z","end":"2026-02-09T18:49:45.970522Z","steps":["trace[1027123138] 'process raft request' (duration: 297.621182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.970726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.613553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:49:45.970756Z","caller":"traceutil/trace.go:171","msg":"trace[1829972957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"307.66077ms","start":"2026-02-09T18:49:45.663088Z","end":"2026-02-09T18:49:45.970749Z","steps":["trace[1829972957] 'agreement among raft nodes before linearized reading' (duration: 307.616073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:45.970775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:45.66308Z","time spent":"307.691078ms","remote":"127.0.0.1:43406","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-09T18:49:45.970923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.221392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-09T18:49:45.970947Z","caller":"traceutil/trace.go:171","msg":"trace[663295039] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:533; }","duration":"283.28116ms","start":"2026-02-09T18:49:45.68766Z","end":"2026-02-09T18:49:45.970941Z","steps":["trace[663295039] 'agreement among raft nodes before linearized reading' (duration: 283.198974ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:46.65054Z","caller":"traceutil/trace.go:171","msg":"trace[490196312] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"101.335483ms","start":"2026-02-09T18:49:46.54919Z","end":"2026-02-09T18:49:46.650526Z","steps":["trace[490196312] 'process raft request' (duration: 29.994516ms)","trace[490196312] 'compare' (duration: 71.183915ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:49:46.664739Z","caller":"traceutil/trace.go:171","msg":"trace[628995334] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"101.764983ms","start":"2026-02-09T18:49:46.562959Z","end":"2026-02-09T18:49:46.664724Z","steps":["trace[628995334] 'process raft request' (duration: 100.191509ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:47.842928Z","caller":"traceutil/trace.go:171","msg":"trace[2064355010] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"178.452795ms","start":"2026-02-09T18:49:47.664462Z","end":"2026-02-09T18:49:47.842915Z","steps":["trace[2064355010] 'process raft request' (duration: 178.318651ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:47.843222Z","caller":"traceutil/trace.go:171","msg":"trace[36258221] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:570; }","duration":"178.201187ms","start":"2026-02-09T18:49:47.665015Z","end":"2026-02-09T18:49:47.843216Z","steps":["trace[36258221] 'read index received' (duration: 178.186928ms)","trace[36258221] 'applied index is now lower than readState.Index' (duration: 1.97µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:49:47.843292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.251755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T18:49:47.843311Z","caller":"traceutil/trace.go:171","msg":"trace[510534729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"178.300722ms","start":"2026-02-09T18:49:47.665005Z","end":"2026-02-09T18:49:47.843306Z","steps":["trace[510534729] 'agreement among raft nodes before linearized reading' (duration: 178.243995ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:47.981755Z","caller":"traceutil/trace.go:171","msg":"trace[1782886204] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"127.711122ms","start":"2026-02-09T18:49:47.854029Z","end":"2026-02-09T18:49:47.98174Z","steps":["trace[1782886204] 'process raft request' (duration: 127.681024ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:47.982042Z","caller":"traceutil/trace.go:171","msg":"trace[2138582625] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"241.677301ms","start":"2026-02-09T18:49:47.740358Z","end":"2026-02-09T18:49:47.982036Z","steps":["trace[2138582625] 'process raft request' (duration: 241.319297ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:49:47.982133Z","caller":"traceutil/trace.go:171","msg":"trace[1838399103] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"138.888175ms","start":"2026-02-09T18:49:47.843239Z","end":"2026-02-09T18:49:47.982127Z","steps":["trace[1838399103] 'read index received' (duration: 67.0144ms)","trace[1838399103] 'applied index is now lower than readState.Index' (duration: 71.873215ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:49:47.982218Z","caller":"traceutil/trace.go:171","msg":"trace[1232572440] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"315.730976ms","start":"2026-02-09T18:49:47.666482Z","end":"2026-02-09T18:49:47.982213Z","steps":["trace[1232572440] 'process raft request' (duration: 243.765845ms)","trace[1232572440] 'compare' (duration: 71.351739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:49:47.982376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:47.666476Z","time spent":"315.753655ms","remote":"127.0.0.1:43718","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-09T18:49:47.982427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.088667ms","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-09T18:49:47.982458Z","caller":"traceutil/trace.go:171","msg":"trace[1632101241] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:555; }","duration":"206.115485ms","start":"2026-02-09T18:49:47.776322Z","end":"2026-02-09T18:49:47.982438Z","steps":["trace[1632101241] 'agreement among raft nodes before linearized reading' (duration: 205.977311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:47.983005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.685999ms","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-09T18:49:47.983025Z","caller":"traceutil/trace.go:171","msg":"trace[1325474558] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:555; }","duration":"206.715468ms","start":"2026-02-09T18:49:47.776305Z","end":"2026-02-09T18:49:47.983021Z","steps":["trace[1325474558] 'agreement among raft nodes before linearized reading' (duration: 206.685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:47.983077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.856872ms","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-09T18:49:47.983093Z","caller":"traceutil/trace.go:171","msg":"trace[1817700146] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:555; }","duration":"206.89302ms","start":"2026-02-09T18:49:47.776196Z","end":"2026-02-09T18:49:47.983089Z","steps":["trace[1817700146] 'agreement among raft nodes before linearized reading' (duration: 206.870641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:47.983129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.860776ms","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-09T18:49:47.983143Z","caller":"traceutil/trace.go:171","msg":"trace[1588135568] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:555; }","duration":"242.881215ms","start":"2026-02-09T18:49:47.740259Z","end":"2026-02-09T18:49:47.98314Z","steps":["trace[1588135568] 'agreement among raft nodes before linearized reading' (duration: 242.861056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:47.983216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.968761ms","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-09T18:49:47.983239Z","caller":"traceutil/trace.go:171","msg":"trace[439740039] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:555; }","duration":"242.999799ms","start":"2026-02-09T18:49:47.740235Z","end":"2026-02-09T18:49:47.983235Z","steps":["trace[439740039] 'agreement among raft nodes before linearized reading' (duration: 242.970421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:47.983292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.068937ms","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-09T18:49:47.983307Z","caller":"traceutil/trace.go:171","msg":"trace[690710724] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:555; }","duration":"243.084686ms","start":"2026-02-09T18:49:47.740219Z","end":"2026-02-09T18:49:47.983304Z","steps":["trace[690710724] 'agreement among raft nodes before linearized reading' (duration: 243.062787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:47.983369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.680141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-09T18:49:47.983386Z","caller":"traceutil/trace.go:171","msg":"trace[337427808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"308.70322ms","start":"2026-02-09T18:49:47.674678Z","end":"2026-02-09T18:49:47.983381Z","steps":["trace[337427808] 'agreement among raft nodes before linearized reading' (duration: 308.676812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:47.983397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:47.674673Z","time spent":"308.72102ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:49:47.98346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.053334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-09T18:49:47.983475Z","caller":"traceutil/trace.go:171","msg":"trace[1145813530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"309.077283ms","start":"2026-02-09T18:49:47.674394Z","end":"2026-02-09T18:49:47.983471Z","steps":["trace[1145813530] 'agreement among raft nodes before linearized reading' (duration: 309.052674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:47.983487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:47.674389Z","time spent":"309.095003ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:49:47.983555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.122151ms","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":"info","ts":"2026-02-09T18:49:47.98357Z","caller":"traceutil/trace.go:171","msg":"trace[547358635] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:555; }","duration":"130.13822ms","start":"2026-02-09T18:49:47.853429Z","end":"2026-02-09T18:49:47.983567Z","steps":["trace[547358635] 'agreement among raft nodes before linearized reading' (duration: 130.109061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:47.983712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.224842ms","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-09T18:49:47.98373Z","caller":"traceutil/trace.go:171","msg":"trace[168239633] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:555; }","duration":"129.265081ms","start":"2026-02-09T18:49:47.854461Z","end":"2026-02-09T18:49:47.983726Z","steps":["trace[168239633] 'agreement among raft nodes before linearized reading' (duration: 129.238342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:47.983789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.328051ms","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":"info","ts":"2026-02-09T18:49:47.983807Z","caller":"traceutil/trace.go:171","msg":"trace[602358639] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:555; }","duration":"130.363959ms","start":"2026-02-09T18:49:47.853437Z","end":"2026-02-09T18:49:47.983801Z","steps":["trace[602358639] 'agreement among raft nodes before linearized reading' (duration: 130.34055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:52.50167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338616313012436,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T18:49:52.56952Z","caller":"traceutil/trace.go:171","msg":"trace[1432262683] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"569.167709ms","start":"2026-02-09T18:49:52.000336Z","end":"2026-02-09T18:49:52.569503Z","steps":["trace[1432262683] 'process raft request' (duration: 569.081132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:52.569622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:52.000317Z","time spent":"569.249175ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:49:52.610212Z","caller":"traceutil/trace.go:171","msg":"trace[1044872913] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"609.351598ms","start":"2026-02-09T18:49:52.000831Z","end":"2026-02-09T18:49:52.610183Z","steps":["trace[1044872913] 'read index received' (duration: 568.654908ms)","trace[1044872913] 'applied index is now lower than readState.Index' (duration: 40.69599ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:49:52.610337Z","caller":"traceutil/trace.go:171","msg":"trace[1139167048] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"460.874289ms","start":"2026-02-09T18:49:52.149456Z","end":"2026-02-09T18:49:52.61033Z","steps":["trace[1139167048] 'process raft request' (duration: 445.30512ms)","trace[1139167048] 'compare' (duration: 15.340158ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:49:52.610428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:52.14944Z","time spent":"460.925137ms","remote":"127.0.0.1:43718","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-09T18:49:52.610597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.766933ms","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-09T18:49:52.610613Z","caller":"traceutil/trace.go:171","msg":"trace[701807552] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:565; }","duration":"609.792253ms","start":"2026-02-09T18:49:52.000816Z","end":"2026-02-09T18:49:52.610608Z","steps":["trace[701807552] 'agreement among raft nodes before linearized reading' (duration: 609.707486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:52.610627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:52.000793Z","time spent":"609.831881ms","remote":"127.0.0.1:43718","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-09T18:49:52.6107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.58392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-09T18:49:52.610711Z","caller":"traceutil/trace.go:171","msg":"trace[392803712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"588.612829ms","start":"2026-02-09T18:49:52.022095Z","end":"2026-02-09T18:49:52.610708Z","steps":["trace[392803712] 'agreement among raft nodes before linearized reading' (duration: 588.57846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:52.611047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.31181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-09T18:49:52.611092Z","caller":"traceutil/trace.go:171","msg":"trace[1368618349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"588.374668ms","start":"2026-02-09T18:49:52.022706Z","end":"2026-02-09T18:49:52.611081Z","steps":["trace[1368618349] 'agreement among raft nodes before linearized reading' (duration: 588.256932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:52.61112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:52.022696Z","time spent":"588.420017ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:49:52.610723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:52.022083Z","time spent":"588.637358ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:49:55.017902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338616313012460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T18:49:55.481503Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.540128426s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T18:49:55.482241Z","caller":"traceutil/trace.go:171","msg":"trace[1649284498] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.540924929s","start":"2026-02-09T18:49:53.941294Z","end":"2026-02-09T18:49:55.482219Z","steps":["trace[1649284498] 'process raft request' (duration: 1.540816033s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:55.482671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:53.941285Z","time spent":"1.541317407s","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:49:55.4929Z","caller":"traceutil/trace.go:171","msg":"trace[1853528384] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"976.03547ms","start":"2026-02-09T18:49:54.516847Z","end":"2026-02-09T18:49:55.492882Z","steps":["trace[1853528384] 'read index received' (duration: 966.319555ms)","trace[1853528384] 'applied index is now lower than readState.Index' (duration: 9.715215ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:49:55.493123Z","caller":"traceutil/trace.go:171","msg":"trace[1841031109] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.551662213s","start":"2026-02-09T18:49:53.941455Z","end":"2026-02-09T18:49:55.493117Z","steps":["trace[1841031109] 'process raft request' (duration: 1.551362893s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:55.4935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:53.941447Z","time spent":"1.551995462s","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:49:55.493624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.775577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:49:55.493702Z","caller":"traceutil/trace.go:171","msg":"trace[237282020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"976.871623ms","start":"2026-02-09T18:49:54.516822Z","end":"2026-02-09T18:49:55.493694Z","steps":["trace[237282020] 'agreement among raft nodes before linearized reading' (duration: 976.784966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:55.493749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:54.516809Z","time spent":"976.934621ms","remote":"127.0.0.1:43406","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-09T18:49:55.493997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.010657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T18:49:55.494041Z","caller":"traceutil/trace.go:171","msg":"trace[103170010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"922.083464ms","start":"2026-02-09T18:49:54.571952Z","end":"2026-02-09T18:49:55.494036Z","steps":["trace[103170010] 'agreement among raft nodes before linearized reading' (duration: 921.90316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:55.494088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:54.571932Z","time spent":"922.150173ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:49:55.494243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.501111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T18:49:55.494409Z","caller":"traceutil/trace.go:171","msg":"trace[87839796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:574; }","duration":"796.667966ms","start":"2026-02-09T18:49:54.697724Z","end":"2026-02-09T18:49:55.494392Z","steps":["trace[87839796] 'agreement among raft nodes before linearized reading' (duration: 796.486632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:55.49445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:54.697718Z","time spent":"796.726184ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T18:49:55.494648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.510546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-09T18:49:55.494736Z","caller":"traceutil/trace.go:171","msg":"trace[182907206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"800.617592ms","start":"2026-02-09T18:49:54.694113Z","end":"2026-02-09T18:49:55.494731Z","steps":["trace[182907206] 'agreement among raft nodes before linearized reading' (duration: 800.502605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:55.494798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:54.694101Z","time spent":"800.67941ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:49:55.494927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.304005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-09T18:49:55.495019Z","caller":"traceutil/trace.go:171","msg":"trace[1511240346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"822.428862ms","start":"2026-02-09T18:49:54.672584Z","end":"2026-02-09T18:49:55.495013Z","steps":["trace[1511240346] 'agreement among raft nodes before linearized reading' (duration: 822.313655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:49:55.495177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:54.672565Z","time spent":"822.605805ms","remote":"127.0.0.1:43718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-09T18:51:09.866855Z","caller":"traceutil/trace.go:171","msg":"trace[1279329572] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"125.8484ms","start":"2026-02-09T18:51:09.740981Z","end":"2026-02-09T18:51:09.866829Z","steps":["trace[1279329572] 'process raft request' (duration: 125.715965ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:51:38.829809Z","caller":"traceutil/trace.go:171","msg":"trace[895136090] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"111.910702ms","start":"2026-02-09T18:51:38.717882Z","end":"2026-02-09T18:51:38.829793Z","steps":["trace[895136090] 'read index received' (duration: 109.11944ms)","trace[895136090] 'applied index is now lower than readState.Index' (duration: 2.790712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:51:38.82997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.06153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1780"} {"level":"info","ts":"2026-02-09T18:51:38.829994Z","caller":"traceutil/trace.go:171","msg":"trace[1942928497] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:1021; }","duration":"112.128323ms","start":"2026-02-09T18:51:38.717858Z","end":"2026-02-09T18:51:38.829987Z","steps":["trace[1942928497] 'agreement among raft nodes before linearized reading' (duration: 111.996157ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:51:38.83016Z","caller":"traceutil/trace.go:171","msg":"trace[295752985] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"172.580304ms","start":"2026-02-09T18:51:38.657557Z","end":"2026-02-09T18:51:38.830137Z","steps":["trace[295752985] 'process raft request' (duration: 169.484046ms)"],"step_count":1}