{"level":"warn","ts":"2026-02-27T18:14:07.867951Z","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-27T18:14:07.868054Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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://162.253.55.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-27T18:14:07.868146Z","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-27T18:14:07.868156Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-27T18:14:07.868175Z","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-27T18:14:07.86887Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-02-27T18:14:07.868932Z","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://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-27T18:14:07.873608Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.482447ms"} {"level":"info","ts":"2026-02-27T18:14:07.886829Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-02-27T18:14:07.886912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T18:14:07.88694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-02-27T18:14:07.886954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T18:14:07.886963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-02-27T18:14:07.887004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-02-27T18:14:07.897686Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T18:14:07.898889Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T18:14:07.899946Z","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-27T18:14:07.901823Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T18:14:07.904714Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T18:14:07.904744Z","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-27T18:14:07.90495Z","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-27T18:14:07.904976Z","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-27T18:14:07.905998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-02-27T18:14:07.906103Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-27T18:14:07.906693Z","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-27T18:14:07.906765Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-27T18:14:07.906777Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-27T18:14:07.906917Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T18:14:07.906966Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T18:14:08.487653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T18:14:08.487724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T18:14:08.487759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-02-27T18:14:08.48778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-02-27T18:14:08.487792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-27T18:14:08.487808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-02-27T18:14:08.487828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-27T18:14:08.546169Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:14:08.546354Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T18:14:08.54635Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T18:14:08.546396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T18:14:08.546761Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T18:14:08.546832Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T18:14:08.547242Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:14:08.547393Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:14:08.547425Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:14:08.550112Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-02-27T18:14:08.550426Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T18:14:09.955828Z","caller":"traceutil/trace.go:171","msg":"trace[1214622269] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"189.986608ms","start":"2026-02-27T18:14:09.765814Z","end":"2026-02-27T18:14:09.955801Z","steps":["trace[1214622269] 'read index received' (duration: 189.924217ms)","trace[1214622269] 'applied index is now lower than readState.Index' (duration: 61.341µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:09.9559Z","caller":"traceutil/trace.go:171","msg":"trace[1860367298] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"195.000766ms","start":"2026-02-27T18:14:09.760874Z","end":"2026-02-27T18:14:09.955875Z","steps":["trace[1860367298] 'process raft request' (duration: 194.714285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:09.956059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.217988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:09.956183Z","caller":"traceutil/trace.go:171","msg":"trace[914084489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:2; }","duration":"190.401619ms","start":"2026-02-27T18:14:09.765769Z","end":"2026-02-27T18:14:09.95617Z","steps":["trace[914084489] 'agreement among raft nodes before linearized reading' (duration: 190.175418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:10.456404Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144247165,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:14:10.957088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144247165,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:14:11.458205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144247165,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:14:11.551196Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.59539767s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T18:14:11.718039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.994536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-27T18:14:11.718301Z","caller":"traceutil/trace.go:171","msg":"trace[2049918640] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.950191999s","start":"2026-02-27T18:14:09.768075Z","end":"2026-02-27T18:14:11.718267Z","steps":["trace[2049918640] 'process raft request' (duration: 1.78328824s)","trace[2049918640] 'compare' (duration: 165.857485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:11.718374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.768062Z","time spent":"1.950280619s","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:14:11.718426Z","caller":"traceutil/trace.go:171","msg":"trace[1260096806] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"1.7624969s","start":"2026-02-27T18:14:09.955916Z","end":"2026-02-27T18:14:11.718413Z","steps":["trace[1260096806] 'read index received' (duration: 1.59545201s)","trace[1260096806] 'applied index is now lower than readState.Index' (duration: 167.04411ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:11.718472Z","caller":"traceutil/trace.go:171","msg":"trace[1148089748] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.879509654s","start":"2026-02-27T18:14:09.838932Z","end":"2026-02-27T18:14:11.718442Z","steps":["trace[1148089748] 'process raft request' (duration: 1.879213133s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.718524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.838915Z","time spent":"1.879588554s","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:11.718494Z","caller":"traceutil/trace.go:171","msg":"trace[1833131704] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.8785279s","start":"2026-02-27T18:14:09.839891Z","end":"2026-02-27T18:14:11.718419Z","steps":["trace[1833131704] 'process raft request' (duration: 1.878311569s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.718551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.827704626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:11.718603Z","caller":"traceutil/trace.go:171","msg":"trace[957007055] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.827784026s","start":"2026-02-27T18:14:09.890807Z","end":"2026-02-27T18:14:11.718591Z","steps":["trace[957007055] 'agreement among raft nodes before linearized reading' (duration: 1.827633146s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.718623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.873414651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:11.718633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.890796Z","time spent":"1.827828906s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-27T18:14:11.71866Z","caller":"traceutil/trace.go:171","msg":"trace[836928057] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.878642091s","start":"2026-02-27T18:14:09.84001Z","end":"2026-02-27T18:14:11.718652Z","steps":["trace[836928057] 'process raft request' (duration: 1.878301119s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.718657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.839875Z","time spent":"1.87870815s","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:11.71867Z","caller":"traceutil/trace.go:171","msg":"trace[869643973] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.873471242s","start":"2026-02-27T18:14:09.845177Z","end":"2026-02-27T18:14:11.718648Z","steps":["trace[869643973] 'agreement among raft nodes before linearized reading' (duration: 1.873332831s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.718707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.840001Z","time spent":"1.878681401s","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:14:11.718712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.845163Z","time spent":"1.873540942s","remote":"127.0.0.1:42132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-02-27T18:14:11.718727Z","caller":"traceutil/trace.go:171","msg":"trace[2013083862] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.87862241s","start":"2026-02-27T18:14:09.840099Z","end":"2026-02-27T18:14:11.718722Z","steps":["trace[2013083862] 'process raft request' (duration: 1.878244218s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.71878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.84009Z","time spent":"1.8786746s","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:11.718804Z","caller":"traceutil/trace.go:171","msg":"trace[775330540] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.878906831s","start":"2026-02-27T18:14:09.839889Z","end":"2026-02-27T18:14:11.718796Z","steps":["trace[775330540] 'process raft request' (duration: 1.878343429s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:11.718804Z","caller":"traceutil/trace.go:171","msg":"trace[686668859] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.870747541s","start":"2026-02-27T18:14:09.848047Z","end":"2026-02-27T18:14:11.718794Z","steps":["trace[686668859] 'process raft request' (duration: 1.87032401s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.718827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.952834569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:11.71884Z","caller":"traceutil/trace.go:171","msg":"trace[1448726642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"1.952862029s","start":"2026-02-27T18:14:09.765973Z","end":"2026-02-27T18:14:11.718836Z","steps":["trace[1448726642] 'agreement among raft nodes before linearized reading' (duration: 1.952841129s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.71884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.839862Z","time spent":"1.878958371s","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:14:11.718851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.765966Z","time spent":"1.952882679s","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T18:14:11.718851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.848038Z","time spent":"1.870788361s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:11.718893Z","caller":"traceutil/trace.go:171","msg":"trace[853942359] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.878913391s","start":"2026-02-27T18:14:09.839974Z","end":"2026-02-27T18:14:11.718887Z","steps":["trace[853942359] 'process raft request' (duration: 1.878314869s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.718918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.904188782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:11.71892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.839964Z","time spent":"1.878941321s","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:11.718932Z","caller":"traceutil/trace.go:171","msg":"trace[1179451383] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.904203112s","start":"2026-02-27T18:14:09.814725Z","end":"2026-02-27T18:14:11.718928Z","steps":["trace[1179451383] 'agreement among raft nodes before linearized reading' (duration: 1.904182292s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.718901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.881111109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:11.718959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.950821122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:11.718972Z","caller":"traceutil/trace.go:171","msg":"trace[1306488044] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"1.952924979s","start":"2026-02-27T18:14:09.766043Z","end":"2026-02-27T18:14:11.718968Z","steps":["trace[1306488044] 'agreement among raft nodes before linearized reading' (duration: 1.952908129s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.71898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.661436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:11.718979Z","caller":"traceutil/trace.go:171","msg":"trace[1333972692] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:0; response_revision:10; }","duration":"1.88123283s","start":"2026-02-27T18:14:09.837724Z","end":"2026-02-27T18:14:11.718957Z","steps":["trace[1333972692] 'agreement among raft nodes before linearized reading' (duration: 1.881094819s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:11.718999Z","caller":"traceutil/trace.go:171","msg":"trace[1940869901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"654.711476ms","start":"2026-02-27T18:14:11.064282Z","end":"2026-02-27T18:14:11.718993Z","steps":["trace[1940869901] 'agreement among raft nodes before linearized reading' (duration: 654.680576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.719016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.064253Z","time spent":"654.759336ms","remote":"127.0.0.1:42024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T18:14:11.718986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.759355029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-sc6kp\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-02-27T18:14:11.719022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.837707Z","time spent":"1.88130807s","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"warn","ts":"2026-02-27T18:14:11.719057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9531582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:11.719062Z","caller":"traceutil/trace.go:171","msg":"trace[1628679050] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-sc6kp; range_end:; response_count:1; response_revision:10; }","duration":"1.759470059s","start":"2026-02-27T18:14:09.959571Z","end":"2026-02-27T18:14:11.719042Z","steps":["trace[1628679050] 'agreement among raft nodes before linearized reading' (duration: 1.759310158s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.719085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.063678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:11.718984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.766036Z","time spent":"1.95294561s","remote":"127.0.0.1:42466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-02-27T18:14:11.719099Z","caller":"traceutil/trace.go:171","msg":"trace[2122194213] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:10; }","duration":"980.110328ms","start":"2026-02-27T18:14:10.738983Z","end":"2026-02-27T18:14:11.719094Z","steps":["trace[2122194213] 'agreement among raft nodes before linearized reading' (duration: 980.093198ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:11.71909Z","caller":"traceutil/trace.go:171","msg":"trace[1112675023] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"1.95323628s","start":"2026-02-27T18:14:09.765844Z","end":"2026-02-27T18:14:11.71908Z","steps":["trace[1112675023] 'agreement among raft nodes before linearized reading' (duration: 1.95319164s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.719112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:10.738962Z","time spent":"980.146508ms","remote":"127.0.0.1:42414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-02-27T18:14:11.719103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.959556Z","time spent":"1.759539429s","remote":"127.0.0.1:42322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":883,"request content":"key:\"/registry/certificatesigningrequests/csr-sc6kp\" "} {"level":"warn","ts":"2026-02-27T18:14:11.719117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.765832Z","time spent":"1.95328s","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":26,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-02-27T18:14:11.71915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.95317383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-02-27T18:14:11.719181Z","caller":"traceutil/trace.go:171","msg":"trace[1569457908] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"1.9532049s","start":"2026-02-27T18:14:09.765967Z","end":"2026-02-27T18:14:11.719172Z","steps":["trace[1569457908] 'agreement among raft nodes before linearized reading' (duration: 1.95315077s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.71906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.997541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:11.719205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.765921Z","time spent":"1.95327749s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3192,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-27T18:14:11.719212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.880242705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:11.718943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.814709Z","time spent":"1.904231793s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-27T18:14:11.719245Z","caller":"traceutil/trace.go:171","msg":"trace[1721707834] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.880274706s","start":"2026-02-27T18:14:09.838962Z","end":"2026-02-27T18:14:11.719237Z","steps":["trace[1721707834] 'agreement among raft nodes before linearized reading' (duration: 1.880227895s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:11.719226Z","caller":"traceutil/trace.go:171","msg":"trace[1639780651] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:10; }","duration":"981.200561ms","start":"2026-02-27T18:14:10.738017Z","end":"2026-02-27T18:14:11.719218Z","steps":["trace[1639780651] 'agreement among raft nodes before linearized reading' (duration: 981.033341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.719271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:09.838952Z","time spent":"1.880313746s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-27T18:14:11.719289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707486251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:11.719297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:10.737997Z","time spent":"981.283292ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-02-27T18:14:11.719323Z","caller":"traceutil/trace.go:171","msg":"trace[107025432] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.707548081s","start":"2026-02-27T18:14:10.011765Z","end":"2026-02-27T18:14:11.719313Z","steps":["trace[107025432] 'agreement among raft nodes before linearized reading' (duration: 1.707502001s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:11.719349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:10.011744Z","time spent":"1.707599831s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-27T18:14:12.225018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144247167,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:14:13.129643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.248959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-27T18:14:13.129746Z","caller":"traceutil/trace.go:171","msg":"trace[1036017082] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.408847217s","start":"2026-02-27T18:14:11.72088Z","end":"2026-02-27T18:14:13.129727Z","steps":["trace[1036017082] 'process raft request' (duration: 736.440168ms)","trace[1036017082] 'compare' (duration: 672.141309ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:13.129804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.720861Z","time spent":"1.408916798s","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:14:13.189591Z","caller":"traceutil/trace.go:171","msg":"trace[1750376463] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.465779174s","start":"2026-02-27T18:14:11.723772Z","end":"2026-02-27T18:14:13.189552Z","steps":["trace[1750376463] 'process raft request' (duration: 1.465605014s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.189741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.72375Z","time spent":"1.465910665s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.189936Z","caller":"traceutil/trace.go:171","msg":"trace[958258510] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:13; }","duration":"1.465697684s","start":"2026-02-27T18:14:11.724212Z","end":"2026-02-27T18:14:13.18991Z","steps":["trace[958258510] 'read index received' (duration: 733.269497ms)","trace[958258510] 'applied index is now lower than readState.Index' (duration: 732.427017ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:13.190009Z","caller":"traceutil/trace.go:171","msg":"trace[229500935] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.461335898s","start":"2026-02-27T18:14:11.728649Z","end":"2026-02-27T18:14:13.189985Z","steps":["trace[229500935] 'process raft request' (duration: 1.461068697s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:13.190069Z","caller":"traceutil/trace.go:171","msg":"trace[1988828219] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"1.461363358s","start":"2026-02-27T18:14:11.728691Z","end":"2026-02-27T18:14:13.190055Z","steps":["trace[1988828219] 'process raft request' (duration: 1.461063927s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.190143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.728684Z","time spent":"1.461425168s","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.190037Z","caller":"traceutil/trace.go:171","msg":"trace[2031563678] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.464908211s","start":"2026-02-27T18:14:11.725108Z","end":"2026-02-27T18:14:13.190017Z","steps":["trace[2031563678] 'process raft request' (duration: 1.464386619s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.190221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.728642Z","time spent":"1.461424478s","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.190278Z","caller":"traceutil/trace.go:171","msg":"trace[330462490] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.461687789s","start":"2026-02-27T18:14:11.728579Z","end":"2026-02-27T18:14:13.190267Z","steps":["trace[330462490] 'process raft request' (duration: 1.461065527s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.190355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.728572Z","time spent":"1.461745129s","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.19044Z","caller":"traceutil/trace.go:171","msg":"trace[361192937] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"1.462380101s","start":"2026-02-27T18:14:11.728052Z","end":"2026-02-27T18:14:13.190432Z","steps":["trace[361192937] 'process raft request' (duration: 1.461571669s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.190485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.728046Z","time spent":"1.462421172s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.190503Z","caller":"traceutil/trace.go:171","msg":"trace[1617299939] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"1.459571732s","start":"2026-02-27T18:14:11.730918Z","end":"2026-02-27T18:14:13.19049Z","steps":["trace[1617299939] 'process raft request' (duration: 1.458884969s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.190475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.725093Z","time spent":"1.465167872s","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:14:13.190579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.73091Z","time spent":"1.459621822s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:14:13.189828Z","caller":"traceutil/trace.go:171","msg":"trace[1613268855] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.46189056s","start":"2026-02-27T18:14:11.727847Z","end":"2026-02-27T18:14:13.189737Z","steps":["trace[1613268855] 'process raft request' (duration: 1.461742959s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.190755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466538857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:13.190797Z","caller":"traceutil/trace.go:171","msg":"trace[1438625474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"1.466581257s","start":"2026-02-27T18:14:11.724205Z","end":"2026-02-27T18:14:13.190786Z","steps":["trace[1438625474] 'agreement among raft nodes before linearized reading' (duration: 1.466520886s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.190811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466260426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:13.190869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.724131Z","time spent":"1.466731037s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T18:14:13.19087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.189685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:13.190885Z","caller":"traceutil/trace.go:171","msg":"trace[163530816] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:20; }","duration":"1.466359346s","start":"2026-02-27T18:14:11.724516Z","end":"2026-02-27T18:14:13.190875Z","steps":["trace[163530816] 'agreement among raft nodes before linearized reading' (duration: 1.466266936s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.190918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.723336Z","time spent":"1.46757393s","remote":"127.0.0.1:42418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-02-27T18:14:13.190927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412128594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:13.190984Z","caller":"traceutil/trace.go:171","msg":"trace[1283853139] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:20; }","duration":"1.412223234s","start":"2026-02-27T18:14:11.778746Z","end":"2026-02-27T18:14:13.190969Z","steps":["trace[1283853139] 'agreement among raft nodes before linearized reading' (duration: 1.412123744s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:13.191018Z","caller":"traceutil/trace.go:171","msg":"trace[722847298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"626.342865ms","start":"2026-02-27T18:14:12.564662Z","end":"2026-02-27T18:14:13.191005Z","steps":["trace[722847298] 'agreement among raft nodes before linearized reading' (duration: 626.129025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.191037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.778729Z","time spent":"1.412294725s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-node-lease\" "} {"level":"warn","ts":"2026-02-27T18:14:13.191069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:12.564594Z","time spent":"626.455406ms","remote":"127.0.0.1:42024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T18:14:13.190554Z","caller":"traceutil/trace.go:171","msg":"trace[218737944] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.46194026s","start":"2026-02-27T18:14:11.728597Z","end":"2026-02-27T18:14:13.190537Z","steps":["trace[218737944] 'process raft request' (duration: 1.461099127s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.191077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412378985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-27T18:14:13.192051Z","caller":"traceutil/trace.go:171","msg":"trace[1203633814] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"1.413346968s","start":"2026-02-27T18:14:11.778689Z","end":"2026-02-27T18:14:13.192036Z","steps":["trace[1203633814] 'agreement among raft nodes before linearized reading' (duration: 1.412355735s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.192163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.77868Z","time spent":"1.413448888s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-27T18:14:13.19112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465201562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-02-27T18:14:13.192425Z","caller":"traceutil/trace.go:171","msg":"trace[260331532] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"1.466556527s","start":"2026-02-27T18:14:11.725855Z","end":"2026-02-27T18:14:13.192411Z","steps":["trace[260331532] 'agreement among raft nodes before linearized reading' (duration: 1.465241762s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.192505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.725848Z","time spent":"1.466645857s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3512,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-27T18:14:13.19208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.728581Z","time spent":"1.463291985s","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:14:13.191119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.727839Z","time spent":"1.462896374s","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:14:13.191253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.15972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-27T18:14:13.193046Z","caller":"traceutil/trace.go:171","msg":"trace[1963850217] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"320.979117ms","start":"2026-02-27T18:14:12.872056Z","end":"2026-02-27T18:14:13.193035Z","steps":["trace[1963850217] 'agreement among raft nodes before linearized reading' (duration: 319.17375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.191242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260991779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:13.193125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:12.872031Z","time spent":"321.082137ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-27T18:14:13.191309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406163353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-27T18:14:13.195389Z","caller":"traceutil/trace.go:171","msg":"trace[1576523242] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"1.410235807s","start":"2026-02-27T18:14:11.785137Z","end":"2026-02-27T18:14:13.195373Z","steps":["trace[1576523242] 'agreement among raft nodes before linearized reading' (duration: 1.406134772s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.195449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.785105Z","time spent":"1.410327018s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-27T18:14:13.189771Z","caller":"traceutil/trace.go:171","msg":"trace[1935170996] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.463708366s","start":"2026-02-27T18:14:11.726034Z","end":"2026-02-27T18:14:13.189742Z","steps":["trace[1935170996] 'process raft request' (duration: 1.463508825s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:13.193164Z","caller":"traceutil/trace.go:171","msg":"trace[1117834740] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"1.262959726s","start":"2026-02-27T18:14:11.930188Z","end":"2026-02-27T18:14:13.193148Z","steps":["trace[1117834740] 'agreement among raft nodes before linearized reading' (duration: 1.261012809s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.195638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.930164Z","time spent":"1.265456945s","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-27T18:14:13.191422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.433292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:13.195763Z","caller":"traceutil/trace.go:171","msg":"trace[507103990] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"272.815328ms","start":"2026-02-27T18:14:12.922934Z","end":"2026-02-27T18:14:13.195749Z","steps":["trace[507103990] 'agreement among raft nodes before linearized reading' (duration: 268.229952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.191311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.593551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-27T18:14:13.195937Z","caller":"traceutil/trace.go:171","msg":"trace[1558634542] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"341.253747ms","start":"2026-02-27T18:14:12.85467Z","end":"2026-02-27T18:14:13.195924Z","steps":["trace[1558634542] 'agreement among raft nodes before linearized reading' (duration: 336.623661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.195977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:12.854638Z","time spent":"341.327647ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-27T18:14:13.195634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:11.72602Z","time spent":"1.469587178s","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.605396Z","caller":"traceutil/trace.go:171","msg":"trace[888232490] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"407.929062ms","start":"2026-02-27T18:14:13.197446Z","end":"2026-02-27T18:14:13.605375Z","steps":["trace[888232490] 'process raft request' (duration: 401.55565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.605553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.197432Z","time spent":"408.047083ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.606468Z","caller":"traceutil/trace.go:171","msg":"trace[617492498] linearizableReadLoop","detail":"{readStateIndex:27; appliedIndex:24; }","duration":"408.017132ms","start":"2026-02-27T18:14:13.198442Z","end":"2026-02-27T18:14:13.606459Z","steps":["trace[617492498] 'read index received' (duration: 401.100018ms)","trace[617492498] 'applied index is now lower than readState.Index' (duration: 6.916524ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:13.606497Z","caller":"traceutil/trace.go:171","msg":"trace[1551973397] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"408.236123ms","start":"2026-02-27T18:14:13.198235Z","end":"2026-02-27T18:14:13.606471Z","steps":["trace[1551973397] 'process raft request' (duration: 408.016822ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:13.606547Z","caller":"traceutil/trace.go:171","msg":"trace[1124392827] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"407.918082ms","start":"2026-02-27T18:14:13.198624Z","end":"2026-02-27T18:14:13.606543Z","steps":["trace[1124392827] 'process raft request' (duration: 407.686581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.606582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.19862Z","time spent":"407.941202ms","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:14:13.606609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.198222Z","time spent":"408.333023ms","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.606824Z","caller":"traceutil/trace.go:171","msg":"trace[1968129852] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"407.25365ms","start":"2026-02-27T18:14:13.199552Z","end":"2026-02-27T18:14:13.606805Z","steps":["trace[1968129852] 'process raft request' (duration: 406.888119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.606892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.199544Z","time spent":"407.31595ms","remote":"127.0.0.1:42430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.606904Z","caller":"traceutil/trace.go:171","msg":"trace[1979780193] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"408.556674ms","start":"2026-02-27T18:14:13.19832Z","end":"2026-02-27T18:14:13.606877Z","steps":["trace[1979780193] 'process raft request' (duration: 408.009893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.60698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.530184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:13.607006Z","caller":"traceutil/trace.go:171","msg":"trace[123337657] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:32; }","duration":"408.589775ms","start":"2026-02-27T18:14:13.198409Z","end":"2026-02-27T18:14:13.606998Z","steps":["trace[123337657] 'agreement among raft nodes before linearized reading' (duration: 408.462534ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:13.606526Z","caller":"traceutil/trace.go:171","msg":"trace[621536217] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"407.974542ms","start":"2026-02-27T18:14:13.198545Z","end":"2026-02-27T18:14:13.60652Z","steps":["trace[621536217] 'process raft request' (duration: 407.738881ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:13.607048Z","caller":"traceutil/trace.go:171","msg":"trace[1884455299] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"407.662441ms","start":"2026-02-27T18:14:13.199375Z","end":"2026-02-27T18:14:13.607037Z","steps":["trace[1884455299] 'process raft request' (duration: 407.047629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.607069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.198534Z","time spent":"408.510654ms","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:14:13.607112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.551404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:13.607107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.199364Z","time spent":"407.707871ms","remote":"127.0.0.1:42162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.607129Z","caller":"traceutil/trace.go:171","msg":"trace[100557961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32; }","duration":"307.597345ms","start":"2026-02-27T18:14:13.299526Z","end":"2026-02-27T18:14:13.607123Z","steps":["trace[100557961] 'agreement among raft nodes before linearized reading' (duration: 307.568135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.607144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.29951Z","time spent":"307.630665ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T18:14:13.607025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.198393Z","time spent":"408.628385ms","remote":"127.0.0.1:42162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":26,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"info","ts":"2026-02-27T18:14:13.60719Z","caller":"traceutil/trace.go:171","msg":"trace[546315437] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"408.895435ms","start":"2026-02-27T18:14:13.198284Z","end":"2026-02-27T18:14:13.60718Z","steps":["trace[546315437] 'process raft request' (duration: 408.063973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.607236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.326731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:13.607237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.198273Z","time spent":"408.942406ms","remote":"127.0.0.1:42466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.607259Z","caller":"traceutil/trace.go:171","msg":"trace[1553307203] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:32; }","duration":"355.398051ms","start":"2026-02-27T18:14:13.251856Z","end":"2026-02-27T18:14:13.607254Z","steps":["trace[1553307203] 'agreement among raft nodes before linearized reading' (duration: 355.367231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.607069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.19831Z","time spent":"408.672055ms","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:14:13.607056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.600614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T18:14:13.607294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.615101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T18:14:13.607303Z","caller":"traceutil/trace.go:171","msg":"trace[1383817858] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:32; }","duration":"408.958016ms","start":"2026-02-27T18:14:13.198331Z","end":"2026-02-27T18:14:13.607289Z","steps":["trace[1383817858] 'agreement among raft nodes before linearized reading' (duration: 408.712685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.607355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.198325Z","time spent":"409.015836ms","remote":"127.0.0.1:42200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":26,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-02-27T18:14:13.607394Z","caller":"traceutil/trace.go:171","msg":"trace[208254662] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"408.430654ms","start":"2026-02-27T18:14:13.198954Z","end":"2026-02-27T18:14:13.607384Z","steps":["trace[208254662] 'process raft request' (duration: 407.413461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.60745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.198944Z","time spent":"408.474944ms","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.607314Z","caller":"traceutil/trace.go:171","msg":"trace[849463076] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:32; }","duration":"407.637901ms","start":"2026-02-27T18:14:13.199667Z","end":"2026-02-27T18:14:13.607305Z","steps":["trace[849463076] 'agreement among raft nodes before linearized reading' (duration: 407.617971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.607525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.199662Z","time spent":"407.857612ms","remote":"127.0.0.1:42414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-02-27T18:14:13.607275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.251836Z","time spent":"355.436271ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-02-27T18:14:13.60763Z","caller":"traceutil/trace.go:171","msg":"trace[835416822] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"408.606155ms","start":"2026-02-27T18:14:13.199012Z","end":"2026-02-27T18:14:13.607619Z","steps":["trace[835416822] 'process raft request' (duration: 407.388111ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:13.60761Z","caller":"traceutil/trace.go:171","msg":"trace[749820087] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"411.119953ms","start":"2026-02-27T18:14:13.196481Z","end":"2026-02-27T18:14:13.6076Z","steps":["trace[749820087] 'process raft request' (duration: 409.902749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.607689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.199006Z","time spent":"408.652785ms","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:14:13.607698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.196465Z","time spent":"411.214013ms","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:13.606482Z","caller":"traceutil/trace.go:171","msg":"trace[318996895] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"408.388184ms","start":"2026-02-27T18:14:13.198076Z","end":"2026-02-27T18:14:13.606464Z","steps":["trace[318996895] 'process raft request' (duration: 408.139353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:13.613415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:13.198061Z","time spent":"415.284408ms","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:15.210336Z","caller":"traceutil/trace.go:171","msg":"trace[1001456963] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"105.416427ms","start":"2026-02-27T18:14:15.10489Z","end":"2026-02-27T18:14:15.210306Z","steps":["trace[1001456963] 'process raft request' (duration: 105.172096ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:16.32151Z","caller":"traceutil/trace.go:171","msg":"trace[615943071] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"102.959636ms","start":"2026-02-27T18:14:16.218527Z","end":"2026-02-27T18:14:16.321487Z","steps":["trace[615943071] 'process raft request' (duration: 90.471724ms)","trace[615943071] 'compare' (duration: 12.07486ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:16.541001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.576249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:14:16.541119Z","caller":"traceutil/trace.go:171","msg":"trace[1885074589] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:294; }","duration":"117.452554ms","start":"2026-02-27T18:14:16.423633Z","end":"2026-02-27T18:14:16.541086Z","steps":["trace[1885074589] 'read index received' (duration: 4.699885ms)","trace[1885074589] 'applied index is now lower than readState.Index' (duration: 112.751709ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:16.541183Z","caller":"traceutil/trace.go:171","msg":"trace[997038674] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"143.270561ms","start":"2026-02-27T18:14:16.397883Z","end":"2026-02-27T18:14:16.541154Z","steps":["trace[997038674] 'process raft request' (duration: 30.468132ms)","trace[997038674] 'compare' (duration: 112.446728ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:16.541299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.629445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:14:16.541374Z","caller":"traceutil/trace.go:171","msg":"trace[437499667] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:289; }","duration":"117.763605ms","start":"2026-02-27T18:14:16.423592Z","end":"2026-02-27T18:14:16.541355Z","steps":["trace[437499667] 'agreement among raft nodes before linearized reading' (duration: 117.591245ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:16.54538Z","caller":"traceutil/trace.go:171","msg":"trace[158108025] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"121.744449ms","start":"2026-02-27T18:14:16.423619Z","end":"2026-02-27T18:14:16.545363Z","steps":["trace[158108025] 'process raft request' (duration: 121.654578ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:16.567053Z","caller":"traceutil/trace.go:171","msg":"trace[1118995953] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"108.316584ms","start":"2026-02-27T18:14:16.458719Z","end":"2026-02-27T18:14:16.567036Z","steps":["trace[1118995953] 'process raft request' (duration: 108.138304ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:16.874539Z","caller":"traceutil/trace.go:171","msg":"trace[1468698271] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"106.949629ms","start":"2026-02-27T18:14:16.767548Z","end":"2026-02-27T18:14:16.874497Z","steps":["trace[1468698271] 'process raft request' (duration: 69.054242ms)","trace[1468698271] 'compare' (duration: 37.567526ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:17.507738Z","caller":"traceutil/trace.go:171","msg":"trace[1958849477] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"104.263357ms","start":"2026-02-27T18:14:17.403447Z","end":"2026-02-27T18:14:17.50771Z","steps":["trace[1958849477] 'process raft request' (duration: 104.163497ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:17.536921Z","caller":"traceutil/trace.go:171","msg":"trace[1906243334] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:325; }","duration":"117.816592ms","start":"2026-02-27T18:14:17.419079Z","end":"2026-02-27T18:14:17.536895Z","steps":["trace[1906243334] 'read index received' (duration: 88.752525ms)","trace[1906243334] 'applied index is now lower than readState.Index' (duration: 29.063307ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:17.536969Z","caller":"traceutil/trace.go:171","msg":"trace[1322221858] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"131.599527ms","start":"2026-02-27T18:14:17.405312Z","end":"2026-02-27T18:14:17.536911Z","steps":["trace[1322221858] 'process raft request' (duration: 131.355716ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:17.537064Z","caller":"traceutil/trace.go:171","msg":"trace[1850192329] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"131.661588ms","start":"2026-02-27T18:14:17.405391Z","end":"2026-02-27T18:14:17.537052Z","steps":["trace[1850192329] 'process raft request' (duration: 131.380577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:17.537394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.283834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-27T18:14:17.537447Z","caller":"traceutil/trace.go:171","msg":"trace[187924977] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:316; }","duration":"118.356564ms","start":"2026-02-27T18:14:17.419075Z","end":"2026-02-27T18:14:17.537432Z","steps":["trace[187924977] 'agreement among raft nodes before linearized reading' (duration: 118.174073ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:17.537793Z","caller":"traceutil/trace.go:171","msg":"trace[1291026907] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"117.596521ms","start":"2026-02-27T18:14:17.420183Z","end":"2026-02-27T18:14:17.53778Z","steps":["trace[1291026907] 'process raft request' (duration: 116.625308ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:27.5586Z","caller":"traceutil/trace.go:171","msg":"trace[1883192904] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"120.203186ms","start":"2026-02-27T18:14:27.438375Z","end":"2026-02-27T18:14:27.558579Z","steps":["trace[1883192904] 'process raft request' (duration: 120.071266ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:34.773217Z","caller":"traceutil/trace.go:171","msg":"trace[1034758247] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"115.235973ms","start":"2026-02-27T18:14:34.657926Z","end":"2026-02-27T18:14:34.773162Z","steps":["trace[1034758247] 'process raft request' (duration: 79.109389ms)","trace[1034758247] 'compare' (duration: 35.996374ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:38.971222Z","caller":"traceutil/trace.go:171","msg":"trace[2048243273] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"179.883608ms","start":"2026-02-27T18:14:38.791305Z","end":"2026-02-27T18:14:38.971189Z","steps":["trace[2048243273] 'process raft request' (duration: 179.706358ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:41.649493Z","caller":"traceutil/trace.go:171","msg":"trace[293545745] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"163.903585ms","start":"2026-02-27T18:14:41.485565Z","end":"2026-02-27T18:14:41.649468Z","steps":["trace[293545745] 'process raft request' (duration: 163.756185ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:42.017528Z","caller":"traceutil/trace.go:171","msg":"trace[775894554] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"104.682621ms","start":"2026-02-27T18:14:41.912821Z","end":"2026-02-27T18:14:42.017503Z","steps":["trace[775894554] 'process raft request' (duration: 88.330135ms)","trace[775894554] 'compare' (duration: 16.228025ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:43.1632Z","caller":"traceutil/trace.go:171","msg":"trace[241973251] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"104.087796ms","start":"2026-02-27T18:14:43.059091Z","end":"2026-02-27T18:14:43.163178Z","steps":["trace[241973251] 'process raft request' (duration: 103.943286ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:44.837325Z","caller":"traceutil/trace.go:171","msg":"trace[1134257593] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"126.144195ms","start":"2026-02-27T18:14:44.711157Z","end":"2026-02-27T18:14:44.837301Z","steps":["trace[1134257593] 'process raft request' (duration: 126.024624ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:44.964578Z","caller":"traceutil/trace.go:171","msg":"trace[1587280293] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"117.816973ms","start":"2026-02-27T18:14:44.846742Z","end":"2026-02-27T18:14:44.964559Z","steps":["trace[1587280293] 'process raft request' (duration: 117.767552ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:44.964645Z","caller":"traceutil/trace.go:171","msg":"trace[1511406171] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"123.364638ms","start":"2026-02-27T18:14:44.841258Z","end":"2026-02-27T18:14:44.964623Z","steps":["trace[1511406171] 'process raft request' (duration: 121.385842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:45.724345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.276551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:14:45.724471Z","caller":"traceutil/trace.go:171","msg":"trace[1479173749] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"235.884412ms","start":"2026-02-27T18:14:45.488564Z","end":"2026-02-27T18:14:45.724448Z","steps":["trace[1479173749] 'process raft request' (duration: 121.430411ms)","trace[1479173749] 'compare' (duration: 114.1538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:48.655465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144248215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:14:49.156149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144248215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:14:49.503219Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.51155075s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T18:14:49.50359Z","caller":"traceutil/trace.go:171","msg":"trace[175181843] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"1.349078914s","start":"2026-02-27T18:14:48.154491Z","end":"2026-02-27T18:14:49.50357Z","steps":["trace[175181843] 'read index received' (duration: 1.348874633s)","trace[175181843] 'applied index is now lower than readState.Index' (duration: 203.241µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:49.503692Z","caller":"traceutil/trace.go:171","msg":"trace[1989399720] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.512071331s","start":"2026-02-27T18:14:47.99157Z","end":"2026-02-27T18:14:49.503641Z","steps":["trace[1989399720] 'process raft request' (duration: 1.51187434s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:49.503842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307317641s","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-27T18:14:49.50385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.017221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-27T18:14:49.503842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.187949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:14:49.503883Z","caller":"traceutil/trace.go:171","msg":"trace[71710483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"270.082411ms","start":"2026-02-27T18:14:49.233793Z","end":"2026-02-27T18:14:49.503875Z","steps":["trace[71710483] 'agreement among raft nodes before linearized reading' (duration: 270.013091ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:49.503882Z","caller":"traceutil/trace.go:171","msg":"trace[56760655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"1.307399521s","start":"2026-02-27T18:14:48.196472Z","end":"2026-02-27T18:14:49.503872Z","steps":["trace[56760655] 'agreement among raft nodes before linearized reading' (duration: 1.307272951s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:49.503906Z","caller":"traceutil/trace.go:171","msg":"trace[1228530547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"725.294519ms","start":"2026-02-27T18:14:48.778594Z","end":"2026-02-27T18:14:49.503889Z","steps":["trace[1228530547] 'agreement among raft nodes before linearized reading' (duration: 725.193249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:49.503919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:48.196451Z","time spent":"1.307462512s","remote":"127.0.0.1:42338","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-27T18:14:49.503925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301479386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:14:49.503942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:48.778574Z","time spent":"725.359381ms","remote":"127.0.0.1:42036","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-27T18:14:49.503989Z","caller":"traceutil/trace.go:171","msg":"trace[1001074970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"1.301556546s","start":"2026-02-27T18:14:48.202411Z","end":"2026-02-27T18:14:49.503968Z","steps":["trace[1001074970] 'agreement among raft nodes before linearized reading' (duration: 1.301450205s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:49.504041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:48.202403Z","time spent":"1.301627466s","remote":"127.0.0.1:42024","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-27T18:14:49.503779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349266404s","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-27T18:14:49.504248Z","caller":"traceutil/trace.go:171","msg":"trace[1383237682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"1.349779525s","start":"2026-02-27T18:14:48.154459Z","end":"2026-02-27T18:14:49.504238Z","steps":["trace[1383237682] 'agreement among raft nodes before linearized reading' (duration: 1.349221104s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:49.504274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:48.154442Z","time spent":"1.349824665s","remote":"127.0.0.1:42338","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-27T18:14:49.504473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:47.991554Z","time spent":"1.512204501s","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:14:49.984982Z","caller":"traceutil/trace.go:171","msg":"trace[1893898776] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"477.167184ms","start":"2026-02-27T18:14:49.507752Z","end":"2026-02-27T18:14:49.984919Z","steps":["trace[1893898776] 'process raft request' (duration: 445.46419ms)","trace[1893898776] 'compare' (duration: 31.523344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:49.985145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:49.507731Z","time spent":"477.345655ms","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:14:51.437259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258988622s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:14:51.437772Z","caller":"traceutil/trace.go:171","msg":"trace[1200627239] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.928501158s","start":"2026-02-27T18:14:49.509243Z","end":"2026-02-27T18:14:51.437744Z","steps":["trace[1200627239] 'process raft request' (duration: 1.928074127s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:51.437912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:49.509234Z","time spent":"1.928593049s","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:14:51.437759Z","caller":"traceutil/trace.go:171","msg":"trace[1953299795] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.929599171s","start":"2026-02-27T18:14:49.508113Z","end":"2026-02-27T18:14:51.437712Z","steps":["trace[1953299795] 'process raft request' (duration: 670.108318ms)","trace[1953299795] 'compare' (duration: 1.258925802s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:51.438213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:49.508105Z","time spent":"1.929890172s","remote":"127.0.0.1:42338","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-27T18:14:51.450027Z","caller":"traceutil/trace.go:171","msg":"trace[349415681] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:568; }","duration":"1.671047691s","start":"2026-02-27T18:14:49.77896Z","end":"2026-02-27T18:14:51.450008Z","steps":["trace[349415681] 'read index received' (duration: 174.274846ms)","trace[349415681] 'applied index is now lower than readState.Index' (duration: 1.496771675s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:51.450108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671144311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:14:51.450142Z","caller":"traceutil/trace.go:171","msg":"trace[1456924089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"1.671211071s","start":"2026-02-27T18:14:49.778922Z","end":"2026-02-27T18:14:51.450133Z","steps":["trace[1456924089] 'agreement among raft nodes before linearized reading' (duration: 1.671154501s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:51.450174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:49.778906Z","time spent":"1.671261021s","remote":"127.0.0.1:42024","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-27T18:14:53.009462Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144248233,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:14:53.140428Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.123311531s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T18:14:53.140823Z","caller":"traceutil/trace.go:171","msg":"trace[1546039202] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"631.842605ms","start":"2026-02-27T18:14:52.508957Z","end":"2026-02-27T18:14:53.140799Z","steps":["trace[1546039202] 'read index received' (duration: 631.588064ms)","trace[1546039202] 'applied index is now lower than readState.Index' (duration: 253.291µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:53.14097Z","caller":"traceutil/trace.go:171","msg":"trace[1509600097] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.123921213s","start":"2026-02-27T18:14:52.017039Z","end":"2026-02-27T18:14:53.14096Z","steps":["trace[1509600097] 'process raft request' (duration: 1.123619702s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:53.141087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:52.017022Z","time spent":"1.123984403s","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:14:53.141197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.883842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:14:53.14129Z","caller":"traceutil/trace.go:171","msg":"trace[1051719461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"362.091004ms","start":"2026-02-27T18:14:52.779176Z","end":"2026-02-27T18:14:53.141267Z","steps":["trace[1051719461] 'agreement among raft nodes before linearized reading' (duration: 361.801613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:53.141317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.341947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j88b9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-02-27T18:14:53.141386Z","caller":"traceutil/trace.go:171","msg":"trace[550945254] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j88b9; range_end:; response_count:1; response_revision:558; }","duration":"632.463067ms","start":"2026-02-27T18:14:52.508907Z","end":"2026-02-27T18:14:53.14137Z","steps":["trace[550945254] 'agreement among raft nodes before linearized reading' (duration: 632.221966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:53.14142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:52.50889Z","time spent":"632.523807ms","remote":"127.0.0.1:42244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-j88b9\" "} {"level":"warn","ts":"2026-02-27T18:14:53.141455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:52.779153Z","time spent":"362.196474ms","remote":"127.0.0.1:42024","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-27T18:14:53.942007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144248237,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T18:14:53.998838Z","caller":"traceutil/trace.go:171","msg":"trace[1840467258] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"557.369954ms","start":"2026-02-27T18:14:53.441446Z","end":"2026-02-27T18:14:53.998816Z","steps":["trace[1840467258] 'read index received' (duration: 557.228544ms)","trace[1840467258] 'applied index is now lower than readState.Index' (duration: 140.37µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:53.99893Z","caller":"traceutil/trace.go:171","msg":"trace[826531857] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"845.776481ms","start":"2026-02-27T18:14:53.153124Z","end":"2026-02-27T18:14:53.9989Z","steps":["trace[826531857] 'process raft request' (duration: 845.513591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:53.999069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.603955ms","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-27T18:14:53.999111Z","caller":"traceutil/trace.go:171","msg":"trace[1330530983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"557.702235ms","start":"2026-02-27T18:14:53.441399Z","end":"2026-02-27T18:14:53.999101Z","steps":["trace[1330530983] 'agreement among raft nodes before linearized reading' (duration: 557.548244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:53.999122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:53.1531Z","time spent":"845.909072ms","remote":"127.0.0.1:42244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:14:53.999144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:53.441375Z","time spent":"557.760835ms","remote":"127.0.0.1:42338","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-27T18:14:53.99921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.760552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:14:53.999274Z","caller":"traceutil/trace.go:171","msg":"trace[1863423612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"217.872132ms","start":"2026-02-27T18:14:53.781386Z","end":"2026-02-27T18:14:53.999258Z","steps":["trace[1863423612] 'agreement among raft nodes before linearized reading' (duration: 217.759242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:53.999283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.753725ms","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-27T18:14:53.999322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.368704ms","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-27T18:14:53.999356Z","caller":"traceutil/trace.go:171","msg":"trace[1647146987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"557.877136ms","start":"2026-02-27T18:14:53.441461Z","end":"2026-02-27T18:14:53.999338Z","steps":["trace[1647146987] 'agreement among raft nodes before linearized reading' (duration: 557.642295ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:53.999394Z","caller":"traceutil/trace.go:171","msg":"trace[1902006758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"458.478795ms","start":"2026-02-27T18:14:53.540891Z","end":"2026-02-27T18:14:53.99937Z","steps":["trace[1902006758] 'agreement among raft nodes before linearized reading' (duration: 458.266104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:53.999404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:53.441439Z","time spent":"557.956876ms","remote":"127.0.0.1:42338","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-27T18:14:53.999436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:53.540869Z","time spent":"458.558525ms","remote":"127.0.0.1:42338","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-27T18:14:55.002245Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144248240,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:14:55.503201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144248240,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:14:56.004409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144248240,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:14:56.284749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.999388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:14:56.28485Z","caller":"traceutil/trace.go:171","msg":"trace[268735808] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"2.281726224s","start":"2026-02-27T18:14:54.003106Z","end":"2026-02-27T18:14:56.284832Z","steps":["trace[268735808] 'process raft request' (duration: 2.089571195s)","trace[268735808] 'compare' (duration: 191.836568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:56.28491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:54.003088Z","time spent":"2.281795024s","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:14:56.778676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000098758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-27T18:14:56.778776Z","caller":"traceutil/trace.go:171","msg":"trace[1432064400] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000289299s","start":"2026-02-27T18:14:54.778466Z","end":"2026-02-27T18:14:56.778755Z","steps":["trace[1432064400] 'agreement among raft nodes before linearized reading' (duration: 2.000150088s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:56.778828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:54.778431Z","time spent":"2.000386759s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T18:14:58.128321Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.035563005s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T18:14:58.128793Z","caller":"traceutil/trace.go:171","msg":"trace[155898490] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"4.12526455s","start":"2026-02-27T18:14:54.003503Z","end":"2026-02-27T18:14:58.128768Z","steps":["trace[155898490] 'process raft request' (duration: 4.12518416s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:58.12883Z","caller":"traceutil/trace.go:171","msg":"trace[383689753] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:578; }","duration":"3.627676079s","start":"2026-02-27T18:14:54.501138Z","end":"2026-02-27T18:14:58.128814Z","steps":["trace[383689753] 'read index received' (duration: 1.591625092s)","trace[383689753] 'applied index is now lower than readState.Index' (duration: 2.036050487s)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:58.128852Z","caller":"traceutil/trace.go:171","msg":"trace[1062502917] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"4.125423961s","start":"2026-02-27T18:14:54.00339Z","end":"2026-02-27T18:14:58.128814Z","steps":["trace[1062502917] 'process raft request' (duration: 4.12510763s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:58.128927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:54.003486Z","time spent":"4.125371011s","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:14:58.128885Z","caller":"traceutil/trace.go:171","msg":"trace[666934805] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"3.398952441s","start":"2026-02-27T18:14:54.729919Z","end":"2026-02-27T18:14:58.128872Z","steps":["trace[666934805] 'process raft request' (duration: 3.39881117s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:58.128993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.627846469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-27T18:14:58.129036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:54.003369Z","time spent":"4.125572891s","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:14:58.129052Z","caller":"traceutil/trace.go:171","msg":"trace[1219149785] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:563; }","duration":"3.627988719s","start":"2026-02-27T18:14:54.501048Z","end":"2026-02-27T18:14:58.129037Z","steps":["trace[1219149785] 'agreement among raft nodes before linearized reading' (duration: 3.627838479s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:58.129114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:54.729893Z","time spent":"3.399142001s","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:14:58.129129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:54.501022Z","time spent":"3.628074479s","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"info","ts":"2026-02-27T18:14:58.386917Z","caller":"traceutil/trace.go:171","msg":"trace[1699414433] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"258.039404ms","start":"2026-02-27T18:14:58.128853Z","end":"2026-02-27T18:14:58.386892Z","steps":["trace[1699414433] 'read index received' (duration: 229.2668ms)","trace[1699414433] 'applied index is now lower than readState.Index' (duration: 28.771344ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:14:58.386968Z","caller":"traceutil/trace.go:171","msg":"trace[1284047564] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.265900122s","start":"2026-02-27T18:14:57.121034Z","end":"2026-02-27T18:14:58.386934Z","steps":["trace[1284047564] 'process raft request' (duration: 1.265797092s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:58.386928Z","caller":"traceutil/trace.go:171","msg":"trace[1148710186] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.603343187s","start":"2026-02-27T18:14:56.783548Z","end":"2026-02-27T18:14:58.386892Z","steps":["trace[1148710186] 'process raft request' (duration: 1.574571693s)","trace[1148710186] 'compare' (duration: 28.557243ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:58.387077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600114348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:14:58.387102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:57.121015Z","time spent":"1.266007602s","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:14:58.387114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:56.783525Z","time spent":"1.603539577s","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:14:58.387138Z","caller":"traceutil/trace.go:171","msg":"trace[885112497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"1.600195348s","start":"2026-02-27T18:14:56.786927Z","end":"2026-02-27T18:14:58.387122Z","steps":["trace[885112497] 'agreement among raft nodes before linearized reading' (duration: 1.600022088s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:58.387174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:56.786861Z","time spent":"1.600305709s","remote":"127.0.0.1:42024","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-27T18:14:58.387167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.24170457s","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-27T18:14:58.387224Z","caller":"traceutil/trace.go:171","msg":"trace[1136462983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"3.24181818s","start":"2026-02-27T18:14:55.145394Z","end":"2026-02-27T18:14:58.387212Z","steps":["trace[1136462983] 'agreement among raft nodes before linearized reading' (duration: 3.241600219s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:58.38729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:55.145375Z","time spent":"3.24190622s","remote":"127.0.0.1:42338","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-27T18:14:58.559843Z","caller":"traceutil/trace.go:171","msg":"trace[167883512] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"172.804256ms","start":"2026-02-27T18:14:58.387014Z","end":"2026-02-27T18:14:58.559818Z","steps":["trace[167883512] 'read index received' (duration: 111.758199ms)","trace[167883512] 'applied index is now lower than readState.Index' (duration: 61.044707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:14:58.559894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:58.12998Z","time spent":"429.882097ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-27T18:14:58.55999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.131391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:14:58.560022Z","caller":"traceutil/trace.go:171","msg":"trace[1544550477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"357.232741ms","start":"2026-02-27T18:14:58.202781Z","end":"2026-02-27T18:14:58.560013Z","steps":["trace[1544550477] 'agreement among raft nodes before linearized reading' (duration: 357.150951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:58.560065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:58.202669Z","time spent":"357.387991ms","remote":"127.0.0.1:42024","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-27T18:14:58.560067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.671928ms","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-27T18:14:58.560142Z","caller":"traceutil/trace.go:171","msg":"trace[1819973352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:565; }","duration":"270.812719ms","start":"2026-02-27T18:14:58.289313Z","end":"2026-02-27T18:14:58.560125Z","steps":["trace[1819973352] 'agreement among raft nodes before linearized reading' (duration: 270.569828ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:58.612527Z","caller":"traceutil/trace.go:171","msg":"trace[1929382529] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"221.01662ms","start":"2026-02-27T18:14:58.391491Z","end":"2026-02-27T18:14:58.612508Z","steps":["trace[1929382529] 'process raft request' (duration: 220.899959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:58.612738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.237318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:14:58.612769Z","caller":"traceutil/trace.go:171","msg":"trace[185156874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"224.316468ms","start":"2026-02-27T18:14:58.388445Z","end":"2026-02-27T18:14:58.612761Z","steps":["trace[185156874] 'agreement among raft nodes before linearized reading' (duration: 224.245088ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:14:59.062364Z","caller":"traceutil/trace.go:171","msg":"trace[1662553696] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"406.368137ms","start":"2026-02-27T18:14:58.655965Z","end":"2026-02-27T18:14:59.062333Z","steps":["trace[1662553696] 'process raft request' (duration: 406.182597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:14:59.062524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:14:58.65594Z","time spent":"406.498248ms","remote":"127.0.0.1:42244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:15:00.490584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.784371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:15:00.490663Z","caller":"traceutil/trace.go:171","msg":"trace[1919397864] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"240.710436ms","start":"2026-02-27T18:15:00.249941Z","end":"2026-02-27T18:15:00.490651Z","steps":["trace[1919397864] 'process raft request' (duration: 91.807165ms)","trace[1919397864] 'compare' (duration: 148.680491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:15:02.091669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144248281,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T18:15:02.40168Z","caller":"traceutil/trace.go:171","msg":"trace[439122925] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"811.021727ms","start":"2026-02-27T18:15:01.590642Z","end":"2026-02-27T18:15:02.401664Z","steps":["trace[439122925] 'read index received' (duration: 810.896877ms)","trace[439122925] 'applied index is now lower than readState.Index' (duration: 124.36µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:02.401744Z","caller":"traceutil/trace.go:171","msg":"trace[63584374] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"991.632468ms","start":"2026-02-27T18:15:01.410075Z","end":"2026-02-27T18:15:02.401708Z","steps":["trace[63584374] 'process raft request' (duration: 991.497268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:02.401845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.204018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j88b9\" ","response":"range_response_count:1 size:17786"} {"level":"info","ts":"2026-02-27T18:15:02.401864Z","caller":"traceutil/trace.go:171","msg":"trace[1831959529] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j88b9; range_end:; response_count:1; response_revision:584; }","duration":"811.254808ms","start":"2026-02-27T18:15:01.590603Z","end":"2026-02-27T18:15:02.401858Z","steps":["trace[1831959529] 'agreement among raft nodes before linearized reading' (duration: 811.140418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:02.401843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.474167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:15:02.401895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:01.590588Z","time spent":"811.302458ms","remote":"127.0.0.1:42244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17808,"request content":"key:\"/registry/pods/kube-system/cilium-j88b9\" "} {"level":"warn","ts":"2026-02-27T18:15:02.401901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:01.410056Z","time spent":"991.756449ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:15:02.401994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.112928ms","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-27T18:15:02.402033Z","caller":"traceutil/trace.go:171","msg":"trace[878582820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"227.165298ms","start":"2026-02-27T18:15:02.17485Z","end":"2026-02-27T18:15:02.402015Z","steps":["trace[878582820] 'agreement among raft nodes before linearized reading' (duration: 227.077398ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:02.401907Z","caller":"traceutil/trace.go:171","msg":"trace[29032860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"622.589657ms","start":"2026-02-27T18:15:01.779305Z","end":"2026-02-27T18:15:02.401895Z","steps":["trace[29032860] 'agreement among raft nodes before linearized reading' (duration: 622.478927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:02.402133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.504857ms","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-27T18:15:02.40215Z","caller":"traceutil/trace.go:171","msg":"trace[440560465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"242.558638ms","start":"2026-02-27T18:15:02.159587Z","end":"2026-02-27T18:15:02.402146Z","steps":["trace[440560465] 'agreement among raft nodes before linearized reading' (duration: 242.516037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:02.402178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:01.779285Z","time spent":"622.866228ms","remote":"127.0.0.1:42024","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-27T18:15:02.665272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.245391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:15:02.665399Z","caller":"traceutil/trace.go:171","msg":"trace[1775620430] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"258.684218ms","start":"2026-02-27T18:15:02.406695Z","end":"2026-02-27T18:15:02.665379Z","steps":["trace[1775620430] 'process raft request' (duration: 132.249887ms)","trace[1775620430] 'compare' (duration: 126.124471ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:02.740783Z","caller":"traceutil/trace.go:171","msg":"trace[1808906084] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"324.487526ms","start":"2026-02-27T18:15:02.416272Z","end":"2026-02-27T18:15:02.740759Z","steps":["trace[1808906084] 'process raft request' (duration: 324.319986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:02.741001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:02.416256Z","time spent":"324.606027ms","remote":"127.0.0.1:42244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17955,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:15:02.741066Z","caller":"traceutil/trace.go:171","msg":"trace[466819590] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"334.290841ms","start":"2026-02-27T18:15:02.406745Z","end":"2026-02-27T18:15:02.741036Z","steps":["trace[466819590] 'process raft request' (duration: 333.6982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:02.741274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:02.406724Z","time spent":"334.450091ms","remote":"127.0.0.1:42338","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-27T18:15:02.894099Z","caller":"traceutil/trace.go:171","msg":"trace[1027243046] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"149.19924ms","start":"2026-02-27T18:15:02.744873Z","end":"2026-02-27T18:15:02.894072Z","steps":["trace[1027243046] 'process raft request' (duration: 65.854608ms)","trace[1027243046] 'compare' (duration: 83.236672ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:02.915976Z","caller":"traceutil/trace.go:171","msg":"trace[1552750522] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"170.608154ms","start":"2026-02-27T18:15:02.74535Z","end":"2026-02-27T18:15:02.915959Z","steps":["trace[1552750522] 'process raft request' (duration: 170.511364ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:04.418221Z","caller":"traceutil/trace.go:171","msg":"trace[1088577379] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"240.160228ms","start":"2026-02-27T18:15:04.178035Z","end":"2026-02-27T18:15:04.418195Z","steps":["trace[1088577379] 'process raft request' (duration: 176.577877ms)","trace[1088577379] 'compare' (duration: 63.472571ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:04.418972Z","caller":"traceutil/trace.go:171","msg":"trace[1386547992] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"142.634161ms","start":"2026-02-27T18:15:04.27631Z","end":"2026-02-27T18:15:04.418944Z","steps":["trace[1386547992] 'process raft request' (duration: 142.582931ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:04.608982Z","caller":"traceutil/trace.go:171","msg":"trace[270485732] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"183.319235ms","start":"2026-02-27T18:15:04.425631Z","end":"2026-02-27T18:15:04.60895Z","steps":["trace[270485732] 'process raft request' (duration: 106.113239ms)","trace[270485732] 'compare' (duration: 76.958945ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:04.64316Z","caller":"traceutil/trace.go:171","msg":"trace[1433918708] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"141.134887ms","start":"2026-02-27T18:15:04.502012Z","end":"2026-02-27T18:15:04.643147Z","steps":["trace[1433918708] 'read index received' (duration: 29.744205ms)","trace[1433918708] 'applied index is now lower than readState.Index' (duration: 111.389592ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:04.643237Z","caller":"traceutil/trace.go:171","msg":"trace[1399104225] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"168.387096ms","start":"2026-02-27T18:15:04.47484Z","end":"2026-02-27T18:15:04.643227Z","steps":["trace[1399104225] 'process raft request' (duration: 168.249376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:04.643382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.349407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-27T18:15:04.643438Z","caller":"traceutil/trace.go:171","msg":"trace[413093152] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:622; }","duration":"141.415208ms","start":"2026-02-27T18:15:04.502007Z","end":"2026-02-27T18:15:04.643423Z","steps":["trace[413093152] 'agreement among raft nodes before linearized reading' (duration: 141.229507ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:04.825702Z","caller":"traceutil/trace.go:171","msg":"trace[724962848] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:646; }","duration":"139.360293ms","start":"2026-02-27T18:15:04.686325Z","end":"2026-02-27T18:15:04.825686Z","steps":["trace[724962848] 'read index received' (duration: 64.771844ms)","trace[724962848] 'applied index is now lower than readState.Index' (duration: 74.587729ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:04.825782Z","caller":"traceutil/trace.go:171","msg":"trace[1366466408] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"151.591444ms","start":"2026-02-27T18:15:04.674183Z","end":"2026-02-27T18:15:04.825774Z","steps":["trace[1366466408] 'process raft request' (duration: 151.466374ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:04.825796Z","caller":"traceutil/trace.go:171","msg":"trace[622260132] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"179.266373ms","start":"2026-02-27T18:15:04.646494Z","end":"2026-02-27T18:15:04.82576Z","steps":["trace[622260132] 'process raft request' (duration: 179.020252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:04.826101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.677409ms","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-27T18:15:04.826157Z","caller":"traceutil/trace.go:171","msg":"trace[62725497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"157.786249ms","start":"2026-02-27T18:15:04.66836Z","end":"2026-02-27T18:15:04.826146Z","steps":["trace[62725497] 'agreement among raft nodes before linearized reading' (duration: 157.577519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:04.907948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.748483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:15:04.908062Z","caller":"traceutil/trace.go:171","msg":"trace[989898888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"127.909854ms","start":"2026-02-27T18:15:04.780125Z","end":"2026-02-27T18:15:04.908035Z","steps":["trace[989898888] 'agreement among raft nodes before linearized reading' (duration: 127.741094ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:04.907958Z","caller":"traceutil/trace.go:171","msg":"trace[1314431034] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"209.186759ms","start":"2026-02-27T18:15:04.698746Z","end":"2026-02-27T18:15:04.907933Z","steps":["trace[1314431034] 'process raft request' (duration: 208.876598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:04.908292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.903223ms","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-27T18:15:04.908374Z","caller":"traceutil/trace.go:171","msg":"trace[1211778766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"163.150013ms","start":"2026-02-27T18:15:04.745204Z","end":"2026-02-27T18:15:04.908354Z","steps":["trace[1211778766] 'agreement among raft nodes before linearized reading' (duration: 162.785152ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:04.999756Z","caller":"traceutil/trace.go:171","msg":"trace[1523640649] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"165.305388ms","start":"2026-02-27T18:15:04.834432Z","end":"2026-02-27T18:15:04.999737Z","steps":["trace[1523640649] 'process raft request' (duration: 165.195258ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:05.000151Z","caller":"traceutil/trace.go:171","msg":"trace[1222898276] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"167.879295ms","start":"2026-02-27T18:15:04.832235Z","end":"2026-02-27T18:15:05.000114Z","steps":["trace[1222898276] 'process raft request' (duration: 167.345374ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:05.00023Z","caller":"traceutil/trace.go:171","msg":"trace[990235885] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"164.522167ms","start":"2026-02-27T18:15:04.835697Z","end":"2026-02-27T18:15:05.000219Z","steps":["trace[990235885] 'process raft request' (duration: 163.976545ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:05.000166Z","caller":"traceutil/trace.go:171","msg":"trace[1151229191] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"173.350138ms","start":"2026-02-27T18:15:04.826792Z","end":"2026-02-27T18:15:05.000142Z","steps":["trace[1151229191] 'process raft request' (duration: 147.138162ms)","trace[1151229191] 'compare' (duration: 25.521075ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:15:05.000817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.213976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T18:15:05.000844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.809183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:15:05.000886Z","caller":"traceutil/trace.go:171","msg":"trace[23793085] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:632; }","duration":"127.878803ms","start":"2026-02-27T18:15:04.872993Z","end":"2026-02-27T18:15:05.000872Z","steps":["trace[23793085] 'agreement among raft nodes before linearized reading' (duration: 127.811013ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:05.000887Z","caller":"traceutil/trace.go:171","msg":"trace[798868231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"101.312806ms","start":"2026-02-27T18:15:04.899556Z","end":"2026-02-27T18:15:05.000869Z","steps":["trace[798868231] 'agreement among raft nodes before linearized reading' (duration: 101.006875ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:05.296221Z","caller":"traceutil/trace.go:171","msg":"trace[584251242] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"216.579116ms","start":"2026-02-27T18:15:05.079629Z","end":"2026-02-27T18:15:05.296208Z","steps":["trace[584251242] 'process raft request' (duration: 216.551326ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:05.296436Z","caller":"traceutil/trace.go:171","msg":"trace[18295338] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"288.079667ms","start":"2026-02-27T18:15:05.008349Z","end":"2026-02-27T18:15:05.296429Z","steps":["trace[18295338] 'process raft request' (duration: 205.165038ms)","trace[18295338] 'compare' (duration: 82.431548ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:05.296551Z","caller":"traceutil/trace.go:171","msg":"trace[1108013925] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"286.110772ms","start":"2026-02-27T18:15:05.010436Z","end":"2026-02-27T18:15:05.296546Z","steps":["trace[1108013925] 'process raft request' (duration: 285.693141ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:05.29664Z","caller":"traceutil/trace.go:171","msg":"trace[1767280356] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"231.738424ms","start":"2026-02-27T18:15:05.064892Z","end":"2026-02-27T18:15:05.296631Z","steps":["trace[1767280356] 'read index received' (duration: 148.622525ms)","trace[1767280356] 'applied index is now lower than readState.Index' (duration: 83.114989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:15:05.296811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.912185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-27T18:15:05.296835Z","caller":"traceutil/trace.go:171","msg":"trace[638783683] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:636; }","duration":"231.983545ms","start":"2026-02-27T18:15:05.064845Z","end":"2026-02-27T18:15:05.296829Z","steps":["trace[638783683] 'agreement among raft nodes before linearized reading' (duration: 231.927615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:05.797185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349831144248373,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T18:15:06.092304Z","caller":"traceutil/trace.go:171","msg":"trace[498707180] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"795.618376ms","start":"2026-02-27T18:15:05.296661Z","end":"2026-02-27T18:15:06.09228Z","steps":["trace[498707180] 'read index received' (duration: 729.33618ms)","trace[498707180] 'applied index is now lower than readState.Index' (duration: 66.281166ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:06.092523Z","caller":"traceutil/trace.go:171","msg":"trace[1457899375] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"813.360681ms","start":"2026-02-27T18:15:05.279122Z","end":"2026-02-27T18:15:06.092482Z","steps":["trace[1457899375] 'process raft request' (duration: 746.836004ms)","trace[1457899375] 'compare' (duration: 66.111126ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:06.092599Z","caller":"traceutil/trace.go:171","msg":"trace[58564649] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"792.955109ms","start":"2026-02-27T18:15:05.299631Z","end":"2026-02-27T18:15:06.092586Z","steps":["trace[58564649] 'process raft request' (duration: 792.603748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:06.092667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:05.27909Z","time spent":"813.503461ms","remote":"127.0.0.1:42096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:15:06.092741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020285113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:15:06.092802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.625388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-27T18:15:06.09282Z","caller":"traceutil/trace.go:171","msg":"trace[1796288964] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:638; }","duration":"1.020364843s","start":"2026-02-27T18:15:05.072432Z","end":"2026-02-27T18:15:06.092797Z","steps":["trace[1796288964] 'agreement among raft nodes before linearized reading' (duration: 1.020265742s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:06.092847Z","caller":"traceutil/trace.go:171","msg":"trace[257209845] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:638; }","duration":"419.728749ms","start":"2026-02-27T18:15:05.673105Z","end":"2026-02-27T18:15:06.092833Z","steps":["trace[257209845] 'agreement among raft nodes before linearized reading' (duration: 419.574818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:06.092871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:05.072426Z","time spent":"1.020435573s","remote":"127.0.0.1:42096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-27T18:15:06.092853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.04619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j88b9\" ","response":"range_response_count:1 size:18333"} {"level":"warn","ts":"2026-02-27T18:15:06.092879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:05.673081Z","time spent":"419.790809ms","remote":"127.0.0.1:42096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-02-27T18:15:06.092931Z","caller":"traceutil/trace.go:171","msg":"trace[1825671024] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j88b9; range_end:; response_count:1; response_revision:638; }","duration":"464.153121ms","start":"2026-02-27T18:15:05.62876Z","end":"2026-02-27T18:15:06.092913Z","steps":["trace[1825671024] 'agreement among raft nodes before linearized reading' (duration: 463.973781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:06.092978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:05.62875Z","time spent":"464.219931ms","remote":"127.0.0.1:42244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18355,"request content":"key:\"/registry/pods/kube-system/cilium-j88b9\" "} {"level":"warn","ts":"2026-02-27T18:15:06.092943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.940115ms","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":"warn","ts":"2026-02-27T18:15:06.092661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.084789ms","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-27T18:15:06.093107Z","caller":"traceutil/trace.go:171","msg":"trace[1763152972] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:638; }","duration":"220.154335ms","start":"2026-02-27T18:15:05.872906Z","end":"2026-02-27T18:15:06.09306Z","steps":["trace[1763152972] 'agreement among raft nodes before linearized reading' (duration: 219.727044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:06.09302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:05.299621Z","time spent":"793.009479ms","remote":"127.0.0.1:42118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:15:06.093285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.981484ms","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-27T18:15:06.093389Z","caller":"traceutil/trace.go:171","msg":"trace[1175541453] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:638; }","duration":"620.163364ms","start":"2026-02-27T18:15:05.473194Z","end":"2026-02-27T18:15:06.093357Z","steps":["trace[1175541453] 'agreement among raft nodes before linearized reading' (duration: 619.282832ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:06.093358Z","caller":"traceutil/trace.go:171","msg":"trace[733752811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:638; }","duration":"797.632841ms","start":"2026-02-27T18:15:05.295502Z","end":"2026-02-27T18:15:06.093134Z","steps":["trace[733752811] 'agreement among raft nodes before linearized reading' (duration: 796.929759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:06.093445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:05.295486Z","time spent":"797.947482ms","remote":"127.0.0.1:42338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T18:15:06.093446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:15:05.473178Z","time spent":"620.255534ms","remote":"127.0.0.1:42096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-27T18:15:06.354256Z","caller":"traceutil/trace.go:171","msg":"trace[1978437577] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"176.703535ms","start":"2026-02-27T18:15:06.177526Z","end":"2026-02-27T18:15:06.354229Z","steps":["trace[1978437577] 'process raft request' (duration: 117.821027ms)","trace[1978437577] 'compare' (duration: 58.626497ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:15:06.858644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.032557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:15:06.858767Z","caller":"traceutil/trace.go:171","msg":"trace[2098644176] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"209.862778ms","start":"2026-02-27T18:15:06.648884Z","end":"2026-02-27T18:15:06.858747Z","steps":["trace[2098644176] 'process raft request' (duration: 83.645281ms)","trace[2098644176] 'compare' (duration: 125.766676ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:06.864147Z","caller":"traceutil/trace.go:171","msg":"trace[2007035626] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"164.383784ms","start":"2026-02-27T18:15:06.699748Z","end":"2026-02-27T18:15:06.864131Z","steps":["trace[2007035626] 'process raft request' (duration: 163.965683ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:07.122915Z","caller":"traceutil/trace.go:171","msg":"trace[1559301915] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"118.246457ms","start":"2026-02-27T18:15:07.004622Z","end":"2026-02-27T18:15:07.122868Z","steps":["trace[1559301915] 'read index received' (duration: 46.465317ms)","trace[1559301915] 'applied index is now lower than readState.Index' (duration: 71.78012ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:07.12374Z","caller":"traceutil/trace.go:171","msg":"trace[153677971] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"186.258498ms","start":"2026-02-27T18:15:06.937467Z","end":"2026-02-27T18:15:07.123726Z","steps":["trace[153677971] 'process raft request' (duration: 113.789577ms)","trace[153677971] 'compare' (duration: 71.287858ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:07.123989Z","caller":"traceutil/trace.go:171","msg":"trace[1979224437] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"155.001959ms","start":"2026-02-27T18:15:06.968975Z","end":"2026-02-27T18:15:07.123976Z","steps":["trace[1979224437] 'process raft request' (duration: 153.818566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:07.12431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.678541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T18:15:07.124385Z","caller":"traceutil/trace.go:171","msg":"trace[1810880859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"119.795671ms","start":"2026-02-27T18:15:07.004577Z","end":"2026-02-27T18:15:07.124373Z","steps":["trace[1810880859] 'agreement among raft nodes before linearized reading' (duration: 119.595511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:07.124726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.170702ms","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-27T18:15:07.124791Z","caller":"traceutil/trace.go:171","msg":"trace[240203319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"116.232992ms","start":"2026-02-27T18:15:07.008524Z","end":"2026-02-27T18:15:07.124757Z","steps":["trace[240203319] 'agreement among raft nodes before linearized reading' (duration: 116.106852ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:07.334354Z","caller":"traceutil/trace.go:171","msg":"trace[792406705] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"138.444997ms","start":"2026-02-27T18:15:07.195881Z","end":"2026-02-27T18:15:07.334326Z","steps":["trace[792406705] 'process raft request' (duration: 64.357481ms)","trace[792406705] 'compare' (duration: 73.719795ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:08.653862Z","caller":"traceutil/trace.go:171","msg":"trace[1227903935] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"143.7052ms","start":"2026-02-27T18:15:08.510137Z","end":"2026-02-27T18:15:08.653842Z","steps":["trace[1227903935] 'process raft request' (duration: 143.505109ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:08.653934Z","caller":"traceutil/trace.go:171","msg":"trace[1565452325] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"113.572284ms","start":"2026-02-27T18:15:08.54019Z","end":"2026-02-27T18:15:08.653762Z","steps":["trace[1565452325] 'read index received' (duration: 113.481814ms)","trace[1565452325] 'applied index is now lower than readState.Index' (duration: 87.06µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:15:08.654082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.869265ms","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-27T18:15:08.654127Z","caller":"traceutil/trace.go:171","msg":"trace[1799476435] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:675; }","duration":"113.964335ms","start":"2026-02-27T18:15:08.540151Z","end":"2026-02-27T18:15:08.654115Z","steps":["trace[1799476435] 'agreement among raft nodes before linearized reading' (duration: 113.878895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:08.654525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.247016ms","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-27T18:15:08.65456Z","caller":"traceutil/trace.go:171","msg":"trace[1794098511] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:676; }","duration":"114.309056ms","start":"2026-02-27T18:15:08.540243Z","end":"2026-02-27T18:15:08.654552Z","steps":["trace[1794098511] 'agreement among raft nodes before linearized reading' (duration: 114.226606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:08.654768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.388426ms","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-27T18:15:08.654788Z","caller":"traceutil/trace.go:171","msg":"trace[1604935195] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:676; }","duration":"114.421626ms","start":"2026-02-27T18:15:08.540362Z","end":"2026-02-27T18:15:08.654783Z","steps":["trace[1604935195] 'agreement among raft nodes before linearized reading' (duration: 114.391226ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:09.263848Z","caller":"traceutil/trace.go:171","msg":"trace[1567511850] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"104.654141ms","start":"2026-02-27T18:15:09.15917Z","end":"2026-02-27T18:15:09.263824Z","steps":["trace[1567511850] 'process raft request' (duration: 88.669231ms)","trace[1567511850] 'compare' (duration: 15.615479ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:09.264073Z","caller":"traceutil/trace.go:171","msg":"trace[803727692] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"104.609791ms","start":"2026-02-27T18:15:09.159453Z","end":"2026-02-27T18:15:09.264063Z","steps":["trace[803727692] 'process raft request' (duration: 104.0852ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:15:09.524865Z","caller":"traceutil/trace.go:171","msg":"trace[1379248396] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"211.209278ms","start":"2026-02-27T18:15:09.313624Z","end":"2026-02-27T18:15:09.524833Z","steps":["trace[1379248396] 'read index received' (duration: 206.511116ms)","trace[1379248396] 'applied index is now lower than readState.Index' (duration: 4.697022ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:15:09.524955Z","caller":"traceutil/trace.go:171","msg":"trace[1396336303] transaction","detail":"{read_only:false; number_of_response:0; response_revision:680; }","duration":"255.022527ms","start":"2026-02-27T18:15:09.269915Z","end":"2026-02-27T18:15:09.524937Z","steps":["trace[1396336303] 'process raft request' (duration: 250.256385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:09.52529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.871344ms","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-27T18:15:09.525343Z","caller":"traceutil/trace.go:171","msg":"trace[1966779154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"145.955264ms","start":"2026-02-27T18:15:09.379373Z","end":"2026-02-27T18:15:09.525328Z","steps":["trace[1966779154] 'agreement among raft nodes before linearized reading' (duration: 145.806814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:15:09.525996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.342041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1667"} {"level":"info","ts":"2026-02-27T18:15:09.526051Z","caller":"traceutil/trace.go:171","msg":"trace[625839127] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:680; }","duration":"212.441051ms","start":"2026-02-27T18:15:09.313597Z","end":"2026-02-27T18:15:09.526038Z","steps":["trace[625839127] 'agreement among raft nodes before linearized reading' (duration: 211.406678ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:21.782437Z","caller":"traceutil/trace.go:171","msg":"trace[462809223] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"133.020079ms","start":"2026-02-27T18:16:21.649355Z","end":"2026-02-27T18:16:21.782375Z","steps":["trace[462809223] 'process raft request' (duration: 53.376504ms)","trace[462809223] 'compare' (duration: 79.434785ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:22.024231Z","caller":"traceutil/trace.go:171","msg":"trace[1261020165] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"153.017625ms","start":"2026-02-27T18:16:21.871183Z","end":"2026-02-27T18:16:22.024201Z","steps":["trace[1261020165] 'process raft request' (duration: 152.823805ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:22.749265Z","caller":"traceutil/trace.go:171","msg":"trace[438100263] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"123.214356ms","start":"2026-02-27T18:16:22.62602Z","end":"2026-02-27T18:16:22.749235Z","steps":["trace[438100263] 'process raft request' (duration: 123.070075ms)"],"step_count":1}