{"level":"warn","ts":"2026-03-19T21:50:24.745361Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T21:50:24.745465Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-03-19T21:50:24.745589Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T21:50:24.745605Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-03-19T21:50:24.745661Z","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-03-19T21:50:24.746317Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-03-19T21:50:24.746418Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-03-19T21:50:24.752537Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.778281ms"} {"level":"info","ts":"2026-03-19T21:50:24.764212Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-03-19T21:50:24.76428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T21:50:24.764309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-03-19T21:50:24.764323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T21:50:24.764334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-03-19T21:50:24.764373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-03-19T21:50:24.769883Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T21:50:24.771705Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T21:50:24.773054Z","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-03-19T21:50:24.77663Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T21:50:24.776883Z","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-03-19T21:50:24.776988Z","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-03-19T21:50:24.777102Z","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-03-19T21:50:24.777033Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T21:50:24.778078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-03-19T21:50:24.779596Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-03-19T21:50:24.782708Z","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-03-19T21:50:24.782976Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-03-19T21:50:24.783016Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-03-19T21:50:24.783096Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T21:50:24.783163Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T21:50:25.065086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T21:50:25.065144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T21:50:25.065194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-03-19T21:50:25.065208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-03-19T21:50:25.065216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-03-19T21:50:25.065227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-03-19T21:50:25.065242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-03-19T21:50:25.066169Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T21:50:25.066192Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:50:25.066879Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T21:50:25.06704Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:50:25.06711Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T21:50:25.067143Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:50:25.067163Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T21:50:25.06704Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T21:50:25.067194Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:50:25.070167Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T21:50:25.070548Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-03-19T21:50:27.043893Z","caller":"traceutil/trace.go:171","msg":"trace[1127923970] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"139.583651ms","start":"2026-03-19T21:50:26.904287Z","end":"2026-03-19T21:50:27.043871Z","steps":["trace[1127923970] 'process raft request' (duration: 139.365986ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:27.044383Z","caller":"traceutil/trace.go:171","msg":"trace[128307999] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"138.400554ms","start":"2026-03-19T21:50:26.905971Z","end":"2026-03-19T21:50:27.044371Z","steps":["trace[128307999] 'read index received' (duration: 137.80355ms)","trace[128307999] 'applied index is now lower than readState.Index' (duration: 593.794µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:50:27.044534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.548988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T21:50:27.044578Z","caller":"traceutil/trace.go:171","msg":"trace[649934618] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:2; }","duration":"138.614089ms","start":"2026-03-19T21:50:26.905954Z","end":"2026-03-19T21:50:27.044568Z","steps":["trace[649934618] 'agreement among raft nodes before linearized reading' (duration: 138.486306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:27.544982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898003563904124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T21:50:28.045682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898003563904124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T21:50:28.069986Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.026240906s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T21:50:28.070397Z","caller":"traceutil/trace.go:171","msg":"trace[1075887156] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.097016144s","start":"2026-03-19T21:50:26.973357Z","end":"2026-03-19T21:50:28.070373Z","steps":["trace[1075887156] 'process raft request' (duration: 1.09687095s)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:28.070511Z","caller":"traceutil/trace.go:171","msg":"trace[816105574] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.096189195s","start":"2026-03-19T21:50:26.97427Z","end":"2026-03-19T21:50:28.070459Z","steps":["trace[816105574] 'process raft request' (duration: 1.096089223s)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:28.070554Z","caller":"traceutil/trace.go:171","msg":"trace[1659950213] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.096197535s","start":"2026-03-19T21:50:26.974324Z","end":"2026-03-19T21:50:28.070522Z","steps":["trace[1659950213] 'process raft request' (duration: 1.096049452s)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:28.070525Z","caller":"traceutil/trace.go:171","msg":"trace[1841296131] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.096319008s","start":"2026-03-19T21:50:26.974134Z","end":"2026-03-19T21:50:28.070453Z","steps":["trace[1841296131] 'process raft request' (duration: 1.096210356s)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:28.070636Z","caller":"traceutil/trace.go:171","msg":"trace[765455114] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.096650865s","start":"2026-03-19T21:50:26.973973Z","end":"2026-03-19T21:50:28.070624Z","steps":["trace[765455114] 'process raft request' (duration: 1.096348718s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.070885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.97426Z","time spent":"1.096318348s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T21:50:28.071151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.974315Z","time spent":"1.096278287s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T21:50:28.071301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.973964Z","time spent":"1.096693457s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T21:50:28.071396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.974127Z","time spent":"1.096513082s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T21:50:28.0715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.973339Z","time spent":"1.097391552s","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T21:50:28.07124Z","caller":"traceutil/trace.go:171","msg":"trace[956302914] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.09154705s","start":"2026-03-19T21:50:26.979682Z","end":"2026-03-19T21:50:28.071229Z","steps":["trace[956302914] 'process raft request' (duration: 1.090725101s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.071933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.979661Z","time spent":"1.092250166s","remote":"127.0.0.1:50176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T21:50:28.071273Z","caller":"traceutil/trace.go:171","msg":"trace[284059325] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.097562166s","start":"2026-03-19T21:50:26.973672Z","end":"2026-03-19T21:50:28.071234Z","steps":["trace[284059325] 'process raft request' (duration: 1.096718537s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.074903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116114184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T21:50:28.074954Z","caller":"traceutil/trace.go:171","msg":"trace[1761962764] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.116160135s","start":"2026-03-19T21:50:26.958782Z","end":"2026-03-19T21:50:28.074942Z","steps":["trace[1761962764] 'agreement among raft nodes before linearized reading' (duration: 1.116105394s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.074984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.958772Z","time spent":"1.116204936s","remote":"127.0.0.1:50128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-19T21:50:28.075013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.973654Z","time spent":"1.101285249s","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T21:50:28.07453Z","caller":"traceutil/trace.go:171","msg":"trace[1849431761] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"1.030008051s","start":"2026-03-19T21:50:27.044422Z","end":"2026-03-19T21:50:28.07443Z","steps":["trace[1849431761] 'read index received' (duration: 1.025781626s)","trace[1849431761] 'applied index is now lower than readState.Index' (duration: 4.225445ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:50:28.074665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168638279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T21:50:28.075197Z","caller":"traceutil/trace.go:171","msg":"trace[2091939486] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"1.169208613s","start":"2026-03-19T21:50:26.905977Z","end":"2026-03-19T21:50:28.075185Z","steps":["trace[2091939486] 'agreement among raft nodes before linearized reading' (duration: 1.168602579s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.075235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.905967Z","time spent":"1.169259283s","remote":"127.0.0.1:50066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-03-19T21:50:28.075276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125940195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T21:50:28.07539Z","caller":"traceutil/trace.go:171","msg":"trace[960228069] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.126054599s","start":"2026-03-19T21:50:26.949324Z","end":"2026-03-19T21:50:28.075379Z","steps":["trace[960228069] 'agreement among raft nodes before linearized reading' (duration: 1.125926535s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.075396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028347564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-w8z98\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-03-19T21:50:28.075422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.949305Z","time spent":"1.12610787s","remote":"127.0.0.1:50128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-19T21:50:28.075424Z","caller":"traceutil/trace.go:171","msg":"trace[2032536436] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-w8z98; range_end:; response_count:1; response_revision:10; }","duration":"1.028401455s","start":"2026-03-19T21:50:27.047014Z","end":"2026-03-19T21:50:28.075416Z","steps":["trace[2032536436] 'agreement among raft nodes before linearized reading' (duration: 1.028340843s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.07546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:27.047003Z","time spent":"1.028450696s","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":885,"request content":"key:\"/registry/certificatesigningrequests/csr-w8z98\" "} {"level":"info","ts":"2026-03-19T21:50:28.074703Z","caller":"traceutil/trace.go:171","msg":"trace[50758969] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.029051829s","start":"2026-03-19T21:50:27.045642Z","end":"2026-03-19T21:50:28.074694Z","steps":["trace[50758969] 'process raft request' (duration: 1.028693371s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.075863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.708521ms","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-03-19T21:50:28.075894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:27.045628Z","time spent":"1.02998103s","remote":"127.0.0.1:50080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:50:28.074737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040427225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T21:50:28.075905Z","caller":"traceutil/trace.go:171","msg":"trace[1726273060] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:10; }","duration":"204.804543ms","start":"2026-03-19T21:50:27.871091Z","end":"2026-03-19T21:50:28.075895Z","steps":["trace[1726273060] 'agreement among raft nodes before linearized reading' (duration: 204.743662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.074794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101791591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T21:50:28.076195Z","caller":"traceutil/trace.go:171","msg":"trace[621133641] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.103237624s","start":"2026-03-19T21:50:26.972946Z","end":"2026-03-19T21:50:28.076183Z","steps":["trace[621133641] 'agreement among raft nodes before linearized reading' (duration: 1.101832021s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.076315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.972924Z","time spent":"1.103370047s","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-03-19T21:50:28.074821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102946216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T21:50:28.076456Z","caller":"traceutil/trace.go:171","msg":"trace[1707156584] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.104566423s","start":"2026-03-19T21:50:26.971868Z","end":"2026-03-19T21:50:28.076434Z","steps":["trace[1707156584] 'agreement among raft nodes before linearized reading' (duration: 1.102939456s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.074848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104287087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T21:50:28.076673Z","caller":"traceutil/trace.go:171","msg":"trace[405456180] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:0; response_revision:10; }","duration":"1.106061378s","start":"2026-03-19T21:50:26.970527Z","end":"2026-03-19T21:50:28.076588Z","steps":["trace[405456180] 'agreement among raft nodes before linearized reading' (duration: 1.104307538s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.074875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11591942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-19T21:50:28.075582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168891176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-03-19T21:50:28.076025Z","caller":"traceutil/trace.go:171","msg":"trace[1638925646] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"1.041749086s","start":"2026-03-19T21:50:27.034254Z","end":"2026-03-19T21:50:28.076003Z","steps":["trace[1638925646] 'agreement among raft nodes before linearized reading' (duration: 1.040466586s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.076864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:27.034242Z","time spent":"1.042526733s","remote":"127.0.0.1:50128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-03-19T21:50:28.07723Z","caller":"traceutil/trace.go:171","msg":"trace[232132811] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.118297664s","start":"2026-03-19T21:50:26.958922Z","end":"2026-03-19T21:50:28.07722Z","steps":["trace[232132811] 'agreement among raft nodes before linearized reading' (duration: 1.11593889s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.07728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.958905Z","time spent":"1.118366195s","remote":"127.0.0.1:50128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-19T21:50:28.077368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.970514Z","time spent":"1.106848955s","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-03-19T21:50:28.074763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-19T21:50:28.076551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.971848Z","time spent":"1.104675226s","remote":"127.0.0.1:50128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-19T21:50:28.077576Z","caller":"traceutil/trace.go:171","msg":"trace[644163268] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"1.17087122s","start":"2026-03-19T21:50:26.906674Z","end":"2026-03-19T21:50:28.077545Z","steps":["trace[644163268] 'agreement among raft nodes before linearized reading' (duration: 1.168869355s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.077686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:26.906668Z","time spent":"1.170962352s","remote":"127.0.0.1:50176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-19T21:50:28.077595Z","caller":"traceutil/trace.go:171","msg":"trace[208619753] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.054156585s","start":"2026-03-19T21:50:27.02343Z","end":"2026-03-19T21:50:28.077587Z","steps":["trace[208619753] 'agreement among raft nodes before linearized reading' (duration: 1.051320421s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:28.077893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:50:27.023413Z","time spent":"1.054462332s","remote":"127.0.0.1:50128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-19T21:50:28.07603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.257664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T21:50:28.078011Z","caller":"traceutil/trace.go:171","msg":"trace[1459261437] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:10; }","duration":"207.270169ms","start":"2026-03-19T21:50:27.870728Z","end":"2026-03-19T21:50:28.077998Z","steps":["trace[1459261437] 'agreement among raft nodes before linearized reading' (duration: 205.282445ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:55.770896Z","caller":"traceutil/trace.go:171","msg":"trace[1335795142] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"231.451955ms","start":"2026-03-19T21:50:55.539422Z","end":"2026-03-19T21:50:55.770874Z","steps":["trace[1335795142] 'process raft request' (duration: 231.324582ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:50:55.771332Z","caller":"traceutil/trace.go:171","msg":"trace[1521364886] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"203.380901ms","start":"2026-03-19T21:50:55.567927Z","end":"2026-03-19T21:50:55.771308Z","steps":["trace[1521364886] 'read index received' (duration: 202.843949ms)","trace[1521364886] 'applied index is now lower than readState.Index' (duration: 535.782µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:50:55.771407Z","caller":"traceutil/trace.go:171","msg":"trace[947641257] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"229.209895ms","start":"2026-03-19T21:50:55.542157Z","end":"2026-03-19T21:50:55.771367Z","steps":["trace[947641257] 'process raft request' (duration: 229.084822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:50:55.771518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.573525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T21:50:55.771595Z","caller":"traceutil/trace.go:171","msg":"trace[1055602700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"203.741049ms","start":"2026-03-19T21:50:55.567835Z","end":"2026-03-19T21:50:55.771576Z","steps":["trace[1055602700] 'agreement among raft nodes before linearized reading' (duration: 203.548255ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:00.95466Z","caller":"traceutil/trace.go:171","msg":"trace[245113498] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"188.907854ms","start":"2026-03-19T21:51:00.76572Z","end":"2026-03-19T21:51:00.954628Z","steps":["trace[245113498] 'read index received' (duration: 188.634048ms)","trace[245113498] 'applied index is now lower than readState.Index' (duration: 272.506µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:00.954738Z","caller":"traceutil/trace.go:171","msg":"trace[561047657] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"717.767572ms","start":"2026-03-19T21:51:00.236933Z","end":"2026-03-19T21:51:00.954701Z","steps":["trace[561047657] 'process raft request' (duration: 717.544377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:00.954832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.096088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:00.954889Z","caller":"traceutil/trace.go:171","msg":"trace[749980205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"189.18938ms","start":"2026-03-19T21:51:00.765686Z","end":"2026-03-19T21:51:00.954875Z","steps":["trace[749980205] 'agreement among raft nodes before linearized reading' (duration: 189.075098ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:00.954916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:00.236914Z","time spent":"717.902905ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:51:01.986214Z","caller":"traceutil/trace.go:171","msg":"trace[1120786763] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"218.38976ms","start":"2026-03-19T21:51:01.767799Z","end":"2026-03-19T21:51:01.986189Z","steps":["trace[1120786763] 'read index received' (duration: 218.077942ms)","trace[1120786763] 'applied index is now lower than readState.Index' (duration: 310.798µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:01.98633Z","caller":"traceutil/trace.go:171","msg":"trace[1955637657] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"249.220116ms","start":"2026-03-19T21:51:01.737072Z","end":"2026-03-19T21:51:01.986292Z","steps":["trace[1955637657] 'process raft request' (duration: 248.93822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:01.986566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.558083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T21:51:01.98659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.096216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T21:51:01.986616Z","caller":"traceutil/trace.go:171","msg":"trace[432193145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"218.84925ms","start":"2026-03-19T21:51:01.767755Z","end":"2026-03-19T21:51:01.986604Z","steps":["trace[432193145] 'agreement among raft nodes before linearized reading' (duration: 218.573164ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:01.986652Z","caller":"traceutil/trace.go:171","msg":"trace[1698387609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"184.188178ms","start":"2026-03-19T21:51:01.802446Z","end":"2026-03-19T21:51:01.986635Z","steps":["trace[1698387609] 'agreement among raft nodes before linearized reading' (duration: 184.090526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:01.986653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.696405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T21:51:01.986695Z","caller":"traceutil/trace.go:171","msg":"trace[1950476292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"186.732255ms","start":"2026-03-19T21:51:01.799949Z","end":"2026-03-19T21:51:01.986682Z","steps":["trace[1950476292] 'agreement among raft nodes before linearized reading' (duration: 186.679904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:01.986595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.82191ms","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-03-19T21:51:01.986817Z","caller":"traceutil/trace.go:171","msg":"trace[434936261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"196.074576ms","start":"2026-03-19T21:51:01.790724Z","end":"2026-03-19T21:51:01.986798Z","steps":["trace[434936261] 'agreement among raft nodes before linearized reading' (duration: 195.80978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:04.296912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898003563905148,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T21:51:04.347004Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.383548942s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T21:51:04.347316Z","caller":"traceutil/trace.go:171","msg":"trace[1107779891] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.383985752s","start":"2026-03-19T21:51:02.963306Z","end":"2026-03-19T21:51:04.347291Z","steps":["trace[1107779891] 'process raft request' (duration: 1.383850439s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:04.347464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:02.963281Z","time spent":"1.384095254s","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:51:04.352619Z","caller":"traceutil/trace.go:171","msg":"trace[517820853] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:545; }","duration":"556.562864ms","start":"2026-03-19T21:51:03.79603Z","end":"2026-03-19T21:51:04.352593Z","steps":["trace[517820853] 'read index received' (duration: 551.180072ms)","trace[517820853] 'applied index is now lower than readState.Index' (duration: 5.380672ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:04.352702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.666866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:51:04.352758Z","caller":"traceutil/trace.go:171","msg":"trace[1021758221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"556.748977ms","start":"2026-03-19T21:51:03.795994Z","end":"2026-03-19T21:51:04.352743Z","steps":["trace[1021758221] 'agreement among raft nodes before linearized reading' (duration: 556.656925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:04.352796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:03.795979Z","time spent":"556.809869ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T21:51:04.352797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.477218ms","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-03-19T21:51:04.352879Z","caller":"traceutil/trace.go:171","msg":"trace[270463061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"310.627361ms","start":"2026-03-19T21:51:04.042242Z","end":"2026-03-19T21:51:04.35287Z","steps":["trace[270463061] 'agreement among raft nodes before linearized reading' (duration: 310.441037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:04.352916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:04.042223Z","time spent":"310.683213ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T21:51:04.353148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.475222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T21:51:04.35328Z","caller":"traceutil/trace.go:171","msg":"trace[764118482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"288.631586ms","start":"2026-03-19T21:51:04.064635Z","end":"2026-03-19T21:51:04.353266Z","steps":["trace[764118482] 'agreement among raft nodes before linearized reading' (duration: 288.099253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:04.353046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.409289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T21:51:04.35364Z","caller":"traceutil/trace.go:171","msg":"trace[882651598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"289.044465ms","start":"2026-03-19T21:51:04.06458Z","end":"2026-03-19T21:51:04.353624Z","steps":["trace[882651598] 'agreement among raft nodes before linearized reading' (duration: 288.195335ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:04.861452Z","caller":"traceutil/trace.go:171","msg":"trace[2081049721] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"131.756684ms","start":"2026-03-19T21:51:04.729667Z","end":"2026-03-19T21:51:04.861424Z","steps":["trace[2081049721] 'process raft request' (duration: 131.613581ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:04.8667Z","caller":"traceutil/trace.go:171","msg":"trace[1960781291] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"125.469773ms","start":"2026-03-19T21:51:04.741206Z","end":"2026-03-19T21:51:04.866676Z","steps":["trace[1960781291] 'process raft request' (duration: 125.33355ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:06.353037Z","caller":"traceutil/trace.go:171","msg":"trace[1647652073] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"390.488044ms","start":"2026-03-19T21:51:05.962531Z","end":"2026-03-19T21:51:06.353019Z","steps":["trace[1647652073] 'process raft request' (duration: 390.387411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:06.353159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:05.962508Z","time spent":"390.591636ms","remote":"127.0.0.1:50058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T21:51:06.355598Z","caller":"traceutil/trace.go:171","msg":"trace[272910740] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"190.416588ms","start":"2026-03-19T21:51:06.165169Z","end":"2026-03-19T21:51:06.355585Z","steps":["trace[272910740] 'process raft request' (duration: 190.262535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:09.156878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.510001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-03-19T21:51:09.156971Z","caller":"traceutil/trace.go:171","msg":"trace[759886840] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:603; }","duration":"198.682365ms","start":"2026-03-19T21:51:08.958269Z","end":"2026-03-19T21:51:09.156952Z","steps":["trace[759886840] 'range keys from in-memory index tree' (duration: 198.316157ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:17.950579Z","caller":"traceutil/trace.go:171","msg":"trace[1396813877] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"104.674593ms","start":"2026-03-19T21:51:17.84589Z","end":"2026-03-19T21:51:17.950564Z","steps":["trace[1396813877] 'read index received' (duration: 104.575651ms)","trace[1396813877] 'applied index is now lower than readState.Index' (duration: 98.052µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:17.950641Z","caller":"traceutil/trace.go:171","msg":"trace[1514211886] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"137.264079ms","start":"2026-03-19T21:51:17.813334Z","end":"2026-03-19T21:51:17.950598Z","steps":["trace[1514211886] 'process raft request' (duration: 137.108235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:17.950752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.856487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T21:51:17.950784Z","caller":"traceutil/trace.go:171","msg":"trace[1274907356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:699; }","duration":"104.943149ms","start":"2026-03-19T21:51:17.845833Z","end":"2026-03-19T21:51:17.950776Z","steps":["trace[1274907356] 'agreement among raft nodes before linearized reading' (duration: 104.806766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:24.69208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.407039ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T21:51:24.692273Z","caller":"traceutil/trace.go:171","msg":"trace[1656879425] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"716.177787ms","start":"2026-03-19T21:51:23.976073Z","end":"2026-03-19T21:51:24.692251Z","steps":["trace[1656879425] 'read index received' (duration: 412.254846ms)","trace[1656879425] 'applied index is now lower than readState.Index' (duration: 303.89458ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:24.692778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.807018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T21:51:24.692891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.892499ms","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-03-19T21:51:24.692913Z","caller":"traceutil/trace.go:171","msg":"trace[178609500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"249.997843ms","start":"2026-03-19T21:51:24.44289Z","end":"2026-03-19T21:51:24.692888Z","steps":["trace[178609500] 'agreement among raft nodes before linearized reading' (duration: 249.669856ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:24.692961Z","caller":"traceutil/trace.go:171","msg":"trace[160125601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"265.023902ms","start":"2026-03-19T21:51:24.427899Z","end":"2026-03-19T21:51:24.692923Z","steps":["trace[160125601] 'agreement among raft nodes before linearized reading' (duration: 264.530581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:24.692987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.582103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T21:51:24.692915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.964964ms","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-03-19T21:51:24.693056Z","caller":"traceutil/trace.go:171","msg":"trace[345130783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"251.125889ms","start":"2026-03-19T21:51:24.441917Z","end":"2026-03-19T21:51:24.693043Z","steps":["trace[345130783] 'agreement among raft nodes before linearized reading' (duration: 250.684149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:24.69432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.261875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T21:51:24.694571Z","caller":"traceutil/trace.go:171","msg":"trace[2106219335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"718.590111ms","start":"2026-03-19T21:51:23.975926Z","end":"2026-03-19T21:51:24.694516Z","steps":["trace[2106219335] 'agreement among raft nodes before linearized reading' (duration: 716.469653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:51:24.6947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:51:23.975872Z","time spent":"718.791746ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-19T21:51:24.694085Z","caller":"traceutil/trace.go:171","msg":"trace[2024138287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"245.656006ms","start":"2026-03-19T21:51:24.447353Z","end":"2026-03-19T21:51:24.693009Z","steps":["trace[2024138287] 'agreement among raft nodes before linearized reading' (duration: 245.205736ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:24.926336Z","caller":"traceutil/trace.go:171","msg":"trace[826083309] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"224.742093ms","start":"2026-03-19T21:51:24.701565Z","end":"2026-03-19T21:51:24.926307Z","steps":["trace[826083309] 'process raft request' (duration: 203.129296ms)","trace[826083309] 'compare' (duration: 21.200058ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:24.926772Z","caller":"traceutil/trace.go:171","msg":"trace[1933581296] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"225.091132ms","start":"2026-03-19T21:51:24.701653Z","end":"2026-03-19T21:51:24.926744Z","steps":["trace[1933581296] 'process raft request' (duration: 224.582679ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:24.926869Z","caller":"traceutil/trace.go:171","msg":"trace[581574054] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"222.267139ms","start":"2026-03-19T21:51:24.70456Z","end":"2026-03-19T21:51:24.926827Z","steps":["trace[581574054] 'process raft request' (duration: 222.195847ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:24.926985Z","caller":"traceutil/trace.go:171","msg":"trace[831698635] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"224.217242ms","start":"2026-03-19T21:51:24.702755Z","end":"2026-03-19T21:51:24.926973Z","steps":["trace[831698635] 'process raft request' (duration: 223.810753ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:49.014711Z","caller":"traceutil/trace.go:171","msg":"trace[1848836037] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"124.131111ms","start":"2026-03-19T21:51:48.890547Z","end":"2026-03-19T21:51:49.014678Z","steps":["trace[1848836037] 'read index received' (duration: 123.890925ms)","trace[1848836037] 'applied index is now lower than readState.Index' (duration: 238.596µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:51:49.014949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.387397ms","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-03-19T21:51:49.015053Z","caller":"traceutil/trace.go:171","msg":"trace[2135036934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:790; }","duration":"124.530311ms","start":"2026-03-19T21:51:48.890503Z","end":"2026-03-19T21:51:49.015033Z","steps":["trace[2135036934] 'agreement among raft nodes before linearized reading' (duration: 124.290505ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:49.117648Z","caller":"traceutil/trace.go:171","msg":"trace[824173104] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"101.853341ms","start":"2026-03-19T21:51:49.015773Z","end":"2026-03-19T21:51:49.117627Z","steps":["trace[824173104] 'process raft request' (duration: 77.388076ms)","trace[824173104] 'compare' (duration: 24.355763ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:51:49.123842Z","caller":"traceutil/trace.go:171","msg":"trace[283719733] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"104.795805ms","start":"2026-03-19T21:51:49.019022Z","end":"2026-03-19T21:51:49.123817Z","steps":["trace[283719733] 'process raft request' (duration: 104.398305ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:49.877033Z","caller":"traceutil/trace.go:171","msg":"trace[768314291] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"232.583239ms","start":"2026-03-19T21:51:49.644423Z","end":"2026-03-19T21:51:49.877006Z","steps":["trace[768314291] 'process raft request' (duration: 232.314892ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:51:51.287077Z","caller":"traceutil/trace.go:171","msg":"trace[1176704734] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"154.31535ms","start":"2026-03-19T21:51:51.132739Z","end":"2026-03-19T21:51:51.287054Z","steps":["trace[1176704734] 'process raft request' (duration: 154.114975ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:03.643633Z","caller":"traceutil/trace.go:171","msg":"trace[1185418909] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"239.910102ms","start":"2026-03-19T21:52:03.403698Z","end":"2026-03-19T21:52:03.643608Z","steps":["trace[1185418909] 'read index received' (duration: 239.648866ms)","trace[1185418909] 'applied index is now lower than readState.Index' (duration: 260.206µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:03.643752Z","caller":"traceutil/trace.go:171","msg":"trace[1714831564] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"291.47849ms","start":"2026-03-19T21:52:03.35222Z","end":"2026-03-19T21:52:03.643698Z","steps":["trace[1714831564] 'process raft request' (duration: 291.204163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:03.643881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.162528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T21:52:03.643934Z","caller":"traceutil/trace.go:171","msg":"trace[344824461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"240.260181ms","start":"2026-03-19T21:52:03.403657Z","end":"2026-03-19T21:52:03.643917Z","steps":["trace[344824461] 'agreement among raft nodes before linearized reading' (duration: 240.087297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:03.643931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.92952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-19T21:52:03.64396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.992849ms","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-03-19T21:52:03.643998Z","caller":"traceutil/trace.go:171","msg":"trace[1227960548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"214.024722ms","start":"2026-03-19T21:52:03.429959Z","end":"2026-03-19T21:52:03.643984Z","steps":["trace[1227960548] 'agreement among raft nodes before linearized reading' (duration: 213.856677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:03.643986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.533304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T21:52:03.644039Z","caller":"traceutil/trace.go:171","msg":"trace[137531856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"239.110992ms","start":"2026-03-19T21:52:03.404907Z","end":"2026-03-19T21:52:03.644018Z","steps":["trace[137531856] 'agreement among raft nodes before linearized reading' (duration: 238.881817ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:03.644064Z","caller":"traceutil/trace.go:171","msg":"trace[1853437691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"210.618096ms","start":"2026-03-19T21:52:03.433422Z","end":"2026-03-19T21:52:03.64404Z","steps":["trace[1853437691] 'agreement among raft nodes before linearized reading' (duration: 210.38459ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:05.894578Z","caller":"traceutil/trace.go:171","msg":"trace[1874640146] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"237.119853ms","start":"2026-03-19T21:52:05.657443Z","end":"2026-03-19T21:52:05.894563Z","steps":["trace[1874640146] 'read index received' (duration: 237.014621ms)","trace[1874640146] 'applied index is now lower than readState.Index' (duration: 104.372µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:05.894686Z","caller":"traceutil/trace.go:171","msg":"trace[1780031078] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"242.408556ms","start":"2026-03-19T21:52:05.652231Z","end":"2026-03-19T21:52:05.89464Z","steps":["trace[1780031078] 'process raft request' (duration: 242.212041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:05.894783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.947437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T21:52:05.8948Z","caller":"traceutil/trace.go:171","msg":"trace[2063480498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:892; }","duration":"224.986438ms","start":"2026-03-19T21:52:05.669807Z","end":"2026-03-19T21:52:05.894793Z","steps":["trace[2063480498] 'agreement among raft nodes before linearized reading' (duration: 224.945987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:05.894728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.267287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T21:52:05.894873Z","caller":"traceutil/trace.go:171","msg":"trace[1525861699] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"237.444211ms","start":"2026-03-19T21:52:05.657399Z","end":"2026-03-19T21:52:05.894843Z","steps":["trace[1525861699] 'agreement among raft nodes before linearized reading' (duration: 237.251296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:05.894898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.991244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T21:52:05.894987Z","caller":"traceutil/trace.go:171","msg":"trace[27278113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:892; }","duration":"226.116256ms","start":"2026-03-19T21:52:05.668848Z","end":"2026-03-19T21:52:05.894965Z","steps":["trace[27278113] 'agreement among raft nodes before linearized reading' (duration: 225.884021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:05.894981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.304597ms","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-03-19T21:52:05.895066Z","caller":"traceutil/trace.go:171","msg":"trace[2070151332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:892; }","duration":"225.416429ms","start":"2026-03-19T21:52:05.669626Z","end":"2026-03-19T21:52:05.895042Z","steps":["trace[2070151332] 'agreement among raft nodes before linearized reading' (duration: 225.144192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:06.714719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.302216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:06.715249Z","caller":"traceutil/trace.go:171","msg":"trace[1359096261] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"815.872248ms","start":"2026-03-19T21:52:05.899362Z","end":"2026-03-19T21:52:06.715235Z","steps":["trace[1359096261] 'process raft request' (duration: 624.992949ms)","trace[1359096261] 'compare' (duration: 190.028477ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:06.715345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:05.899348Z","time spent":"815.96342ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:06.811168Z","caller":"traceutil/trace.go:171","msg":"trace[1757570213] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:928; }","duration":"233.590284ms","start":"2026-03-19T21:52:06.577561Z","end":"2026-03-19T21:52:06.811152Z","steps":["trace[1757570213] 'read index received' (duration: 233.40874ms)","trace[1757570213] 'applied index is now lower than readState.Index' (duration: 180.724µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:06.811203Z","caller":"traceutil/trace.go:171","msg":"trace[486551051] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"911.358248ms","start":"2026-03-19T21:52:05.89982Z","end":"2026-03-19T21:52:06.811178Z","steps":["trace[486551051] 'process raft request' (duration: 911.292536ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:06.811271Z","caller":"traceutil/trace.go:171","msg":"trace[371563241] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"911.371639ms","start":"2026-03-19T21:52:05.899874Z","end":"2026-03-19T21:52:06.811246Z","steps":["trace[371563241] 'process raft request' (duration: 911.259456ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:06.811308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.711177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T21:52:06.811331Z","caller":"traceutil/trace.go:171","msg":"trace[1996633147] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:896; }","duration":"233.81124ms","start":"2026-03-19T21:52:06.577513Z","end":"2026-03-19T21:52:06.811325Z","steps":["trace[1996633147] 'agreement among raft nodes before linearized reading' (duration: 233.724327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:06.811341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:05.899794Z","time spent":"911.470031ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:06.811369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:05.899838Z","time spent":"911.479771ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:06.811748Z","caller":"traceutil/trace.go:171","msg":"trace[1861187599] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"911.877271ms","start":"2026-03-19T21:52:05.899837Z","end":"2026-03-19T21:52:06.811715Z","steps":["trace[1861187599] 'process raft request' (duration: 911.180934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:06.811827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:05.89983Z","time spent":"911.957383ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:20.126775Z","caller":"traceutil/trace.go:171","msg":"trace[1560704121] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"178.824897ms","start":"2026-03-19T21:52:19.947927Z","end":"2026-03-19T21:52:20.126752Z","steps":["trace[1560704121] 'read index received' (duration: 178.565261ms)","trace[1560704121] 'applied index is now lower than readState.Index' (duration: 258.476µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:20.126853Z","caller":"traceutil/trace.go:171","msg":"trace[1889049873] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"184.469499ms","start":"2026-03-19T21:52:19.942352Z","end":"2026-03-19T21:52:20.126822Z","steps":["trace[1889049873] 'process raft request' (duration: 184.246863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:20.12704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.898624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:20.127083Z","caller":"traceutil/trace.go:171","msg":"trace[17738520] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:931; }","duration":"153.001317ms","start":"2026-03-19T21:52:19.974072Z","end":"2026-03-19T21:52:20.127074Z","steps":["trace[17738520] 'agreement among raft nodes before linearized reading' (duration: 152.834373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:20.127172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.189516ms","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-03-19T21:52:20.127329Z","caller":"traceutil/trace.go:171","msg":"trace[865400074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"179.403672ms","start":"2026-03-19T21:52:19.947895Z","end":"2026-03-19T21:52:20.127298Z","steps":["trace[865400074] 'agreement among raft nodes before linearized reading' (duration: 178.95067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.185636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.729758Z","time spent":"455.867603ms","remote":"127.0.0.1:44910","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-19T21:52:21.185973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.34797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:21.186199Z","caller":"traceutil/trace.go:171","msg":"trace[1703903823] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:969; }","duration":"419.780203ms","start":"2026-03-19T21:52:20.766404Z","end":"2026-03-19T21:52:21.186184Z","steps":["trace[1703903823] 'read index received' (duration: 312.216661ms)","trace[1703903823] 'applied index is now lower than readState.Index' (duration: 107.562582ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:21.18629Z","caller":"traceutil/trace.go:171","msg":"trace[1723299304] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"850.629629ms","start":"2026-03-19T21:52:20.335652Z","end":"2026-03-19T21:52:21.186282Z","steps":["trace[1723299304] 'process raft request' (duration: 850.443264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.186367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.335617Z","time spent":"850.711601ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:21.186457Z","caller":"traceutil/trace.go:171","msg":"trace[1324867435] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.054697271s","start":"2026-03-19T21:52:20.131704Z","end":"2026-03-19T21:52:21.186402Z","steps":["trace[1324867435] 'process raft request' (duration: 318.818927ms)","trace[1324867435] 'compare' (duration: 734.940161ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:21.186573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.685958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T21:52:21.18663Z","caller":"traceutil/trace.go:171","msg":"trace[1432818101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"321.843072ms","start":"2026-03-19T21:52:20.86477Z","end":"2026-03-19T21:52:21.186613Z","steps":["trace[1432818101] 'agreement among raft nodes before linearized reading' (duration: 321.608885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.186634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.881847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-19T21:52:21.186624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.131676Z","time spent":"1.054856806s","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:21.186648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.238836ms","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-03-19T21:52:21.18667Z","caller":"traceutil/trace.go:171","msg":"trace[658072424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"314.948759ms","start":"2026-03-19T21:52:20.871711Z","end":"2026-03-19T21:52:21.18666Z","steps":["trace[658072424] 'agreement among raft nodes before linearized reading' (duration: 314.809346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.186673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.864748Z","time spent":"321.912923ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T21:52:21.186682Z","caller":"traceutil/trace.go:171","msg":"trace[810914752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"420.310717ms","start":"2026-03-19T21:52:20.766361Z","end":"2026-03-19T21:52:21.186672Z","steps":["trace[810914752] 'agreement among raft nodes before linearized reading' (duration: 420.171434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.186694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.871701Z","time spent":"314.98874ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T21:52:21.186713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.766342Z","time spent":"420.365189ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T21:52:21.186831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.695178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T21:52:21.186878Z","caller":"traceutil/trace.go:171","msg":"trace[240823989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"321.750339ms","start":"2026-03-19T21:52:20.865108Z","end":"2026-03-19T21:52:21.186858Z","steps":["trace[240823989] 'agreement among raft nodes before linearized reading' (duration: 321.641366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.186914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:20.865093Z","time spent":"321.812221ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T21:52:21.659591Z","caller":"traceutil/trace.go:171","msg":"trace[1914257612] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"469.034147ms","start":"2026-03-19T21:52:21.190523Z","end":"2026-03-19T21:52:21.659557Z","steps":["trace[1914257612] 'process raft request' (duration: 467.1732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.659795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.190508Z","time spent":"469.1869ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:21.848671Z","caller":"traceutil/trace.go:171","msg":"trace[145306755] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"657.924757ms","start":"2026-03-19T21:52:21.190725Z","end":"2026-03-19T21:52:21.84865Z","steps":["trace[145306755] 'process raft request' (duration: 657.823245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.848804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.190713Z","time spent":"658.024259ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:21.848989Z","caller":"traceutil/trace.go:171","msg":"trace[1164897650] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"658.277636ms","start":"2026-03-19T21:52:21.190673Z","end":"2026-03-19T21:52:21.84895Z","steps":["trace[1164897650] 'process raft request' (duration: 637.483894ms)","trace[1164897650] 'compare' (duration: 20.123305ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:21.849143Z","caller":"traceutil/trace.go:171","msg":"trace[825854795] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"657.767633ms","start":"2026-03-19T21:52:21.191362Z","end":"2026-03-19T21:52:21.84913Z","steps":["trace[825854795] 'process raft request' (duration: 657.25222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:21.849208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.190664Z","time spent":"658.40451ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:21.849228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:21.191349Z","time spent":"657.842266ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:23.099341Z","caller":"traceutil/trace.go:171","msg":"trace[1404681553] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"336.109963ms","start":"2026-03-19T21:52:22.763215Z","end":"2026-03-19T21:52:23.099325Z","steps":["trace[1404681553] 'read index received' (duration: 335.129198ms)","trace[1404681553] 'applied index is now lower than readState.Index' (duration: 979.165µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:23.099412Z","caller":"traceutil/trace.go:171","msg":"trace[272269388] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"449.277828ms","start":"2026-03-19T21:52:22.650109Z","end":"2026-03-19T21:52:23.099387Z","steps":["trace[272269388] 'process raft request' (duration: 448.248953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:23.09953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.311877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5183"} {"level":"warn","ts":"2026-03-19T21:52:23.099531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:22.650089Z","time spent":"449.38369ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T21:52:23.099553Z","caller":"traceutil/trace.go:171","msg":"trace[318453550] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:939; }","duration":"336.379839ms","start":"2026-03-19T21:52:22.763167Z","end":"2026-03-19T21:52:23.099547Z","steps":["trace[318453550] 'agreement among raft nodes before linearized reading' (duration: 336.264866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:23.099756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:22.763147Z","time spent":"336.595684ms","remote":"127.0.0.1:50176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5207,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-19T21:52:23.099637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.626751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T21:52:23.09993Z","caller":"traceutil/trace.go:171","msg":"trace[1458925733] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:939; }","duration":"310.95715ms","start":"2026-03-19T21:52:22.788954Z","end":"2026-03-19T21:52:23.099911Z","steps":["trace[1458925733] 'agreement among raft nodes before linearized reading' (duration: 310.56941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:23.09998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:22.788939Z","time spent":"311.026412ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":111,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-19T21:52:24.320395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.500482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:24.321501Z","caller":"traceutil/trace.go:171","msg":"trace[817313135] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"1.128273354s","start":"2026-03-19T21:52:23.193195Z","end":"2026-03-19T21:52:24.321468Z","steps":["trace[817313135] 'process raft request' (duration: 516.606601ms)","trace[817313135] 'compare' (duration: 610.340299ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:24.321653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:23.19318Z","time spent":"1.128399977s","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:24.321677Z","caller":"traceutil/trace.go:171","msg":"trace[1122491313] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"1.105959252s","start":"2026-03-19T21:52:23.21569Z","end":"2026-03-19T21:52:24.321649Z","steps":["trace[1122491313] 'read index received' (duration: 494.087334ms)","trace[1122491313] 'applied index is now lower than readState.Index' (duration: 611.869118ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:24.531272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315567043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:24.531336Z","caller":"traceutil/trace.go:171","msg":"trace[113278555] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:940; }","duration":"1.315683176s","start":"2026-03-19T21:52:23.215636Z","end":"2026-03-19T21:52:24.531319Z","steps":["trace[113278555] 'agreement among raft nodes before linearized reading' (duration: 1.106126967s)","trace[113278555] 'range keys from in-memory index tree' (duration: 209.454897ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:24.531367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:23.215582Z","time spent":"1.315778469s","remote":"127.0.0.1:50382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"warn","ts":"2026-03-19T21:52:24.531429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.931825ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T21:52:24.531598Z","caller":"traceutil/trace.go:171","msg":"trace[151870345] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:978; }","duration":"209.848737ms","start":"2026-03-19T21:52:24.321736Z","end":"2026-03-19T21:52:24.531585Z","steps":["trace[151870345] 'read index received' (duration: 73.187761ms)","trace[151870345] 'applied index is now lower than readState.Index' (duration: 136.660056ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:24.531703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.753294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-19T21:52:24.531714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.589378ms","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-03-19T21:52:24.531733Z","caller":"traceutil/trace.go:171","msg":"trace[2088805789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"677.805215ms","start":"2026-03-19T21:52:23.85392Z","end":"2026-03-19T21:52:24.531725Z","steps":["trace[2088805789] 'agreement among raft nodes before linearized reading' (duration: 677.682072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:24.531811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:23.853908Z","time spent":"677.895128ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T21:52:24.531873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.565315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T21:52:24.531909Z","caller":"traceutil/trace.go:171","msg":"trace[836091181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"678.637187ms","start":"2026-03-19T21:52:23.853264Z","end":"2026-03-19T21:52:24.531901Z","steps":["trace[836091181] 'agreement among raft nodes before linearized reading' (duration: 678.549485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:24.531938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:23.853247Z","time spent":"678.683598ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T21:52:24.531918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.593326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T21:52:24.531936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.402518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:24.531943Z","caller":"traceutil/trace.go:171","msg":"trace[1362283792] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"523.348961ms","start":"2026-03-19T21:52:24.008558Z","end":"2026-03-19T21:52:24.531907Z","steps":["trace[1362283792] 'process raft request' (duration: 522.931891ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:24.532006Z","caller":"traceutil/trace.go:171","msg":"trace[1725565595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"763.514781ms","start":"2026-03-19T21:52:23.768469Z","end":"2026-03-19T21:52:24.531984Z","steps":["trace[1725565595] 'agreement among raft nodes before linearized reading' (duration: 763.410509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:24.532056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:23.768447Z","time spent":"763.597533ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T21:52:24.532081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:24.008528Z","time spent":"523.495594ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T21:52:24.53198Z","caller":"traceutil/trace.go:171","msg":"trace[1337614263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"678.702159ms","start":"2026-03-19T21:52:23.853264Z","end":"2026-03-19T21:52:24.531966Z","steps":["trace[1337614263] 'agreement among raft nodes before linearized reading' (duration: 678.523234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:24.532128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:23.853247Z","time spent":"678.871333ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T21:52:24.532367Z","caller":"traceutil/trace.go:171","msg":"trace[1502443747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"868.280405ms","start":"2026-03-19T21:52:23.664075Z","end":"2026-03-19T21:52:24.532355Z","steps":["trace[1502443747] 'agreement among raft nodes before linearized reading' (duration: 867.560568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:24.532411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:23.664051Z","time spent":"868.353827ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T21:52:24.732188Z","caller":"traceutil/trace.go:171","msg":"trace[65914034] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"196.942672ms","start":"2026-03-19T21:52:24.535226Z","end":"2026-03-19T21:52:24.732169Z","steps":["trace[65914034] 'process raft request' (duration: 196.893331ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:24.732465Z","caller":"traceutil/trace.go:171","msg":"trace[32482925] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"197.208429ms","start":"2026-03-19T21:52:24.535231Z","end":"2026-03-19T21:52:24.73244Z","steps":["trace[32482925] 'process raft request' (duration: 196.85219ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:24.732602Z","caller":"traceutil/trace.go:171","msg":"trace[932572286] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"197.364193ms","start":"2026-03-19T21:52:24.535228Z","end":"2026-03-19T21:52:24.732593Z","steps":["trace[932572286] 'process raft request' (duration: 104.130748ms)","trace[932572286] 'compare' (duration: 92.594379ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:24.750866Z","caller":"traceutil/trace.go:171","msg":"trace[425028051] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"214.477764ms","start":"2026-03-19T21:52:24.536358Z","end":"2026-03-19T21:52:24.750835Z","steps":["trace[425028051] 'process raft request' (duration: 214.416942ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:24.751328Z","caller":"traceutil/trace.go:171","msg":"trace[940425464] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"216.070464ms","start":"2026-03-19T21:52:24.535246Z","end":"2026-03-19T21:52:24.751317Z","steps":["trace[940425464] 'process raft request' (duration: 215.368246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:27.237519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898003563906089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T21:52:27.395272Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.064663834s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T21:52:27.395998Z","caller":"traceutil/trace.go:171","msg":"trace[1540392651] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.065929195s","start":"2026-03-19T21:52:26.330032Z","end":"2026-03-19T21:52:27.395961Z","steps":["trace[1540392651] 'process raft request' (duration: 1.065581267s)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:27.396124Z","caller":"traceutil/trace.go:171","msg":"trace[1327392008] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"659.089415ms","start":"2026-03-19T21:52:26.736688Z","end":"2026-03-19T21:52:27.395777Z","steps":["trace[1327392008] 'read index received' (duration: 658.835528ms)","trace[1327392008] 'applied index is now lower than readState.Index' (duration: 252.997µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:27.396436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.354211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T21:52:27.39646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.325558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T21:52:27.396447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.644255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:27.396481Z","caller":"traceutil/trace.go:171","msg":"trace[670785847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"659.463614ms","start":"2026-03-19T21:52:26.737006Z","end":"2026-03-19T21:52:27.39647Z","steps":["trace[670785847] 'agreement among raft nodes before linearized reading' (duration: 659.270829ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:27.396546Z","caller":"traceutil/trace.go:171","msg":"trace[1691766672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"641.42828ms","start":"2026-03-19T21:52:26.7551Z","end":"2026-03-19T21:52:27.396528Z","steps":["trace[1691766672] 'agreement among raft nodes before linearized reading' (duration: 641.252856ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:27.396569Z","caller":"traceutil/trace.go:171","msg":"trace[2127954462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"571.901602ms","start":"2026-03-19T21:52:26.824637Z","end":"2026-03-19T21:52:27.396539Z","steps":["trace[2127954462] 'agreement among raft nodes before linearized reading' (duration: 571.698847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:27.396568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:26.73698Z","time spent":"659.575797ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T21:52:27.396621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:26.755084Z","time spent":"641.525613ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T21:52:27.396621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.109305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-19T21:52:27.396646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:26.824594Z","time spent":"572.037155ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T21:52:27.396696Z","caller":"traceutil/trace.go:171","msg":"trace[2019380317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:947; }","duration":"562.275889ms","start":"2026-03-19T21:52:26.834403Z","end":"2026-03-19T21:52:27.396679Z","steps":["trace[2019380317] 'agreement among raft nodes before linearized reading' (duration: 561.92457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:27.39674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:26.834384Z","time spent":"562.34797ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T21:52:27.396276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.532435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T21:52:27.396889Z","caller":"traceutil/trace.go:171","msg":"trace[689550698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"660.249873ms","start":"2026-03-19T21:52:26.736627Z","end":"2026-03-19T21:52:27.396877Z","steps":["trace[689550698] 'agreement among raft nodes before linearized reading' (duration: 659.422042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:27.396947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:26.329995Z","time spent":"1.066755096s","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:27.396994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:26.736601Z","time spent":"660.382437ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T21:52:28.07924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.843578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:28.07945Z","caller":"traceutil/trace.go:171","msg":"trace[1920009905] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:987; }","duration":"160.558917ms","start":"2026-03-19T21:52:27.918877Z","end":"2026-03-19T21:52:28.079436Z","steps":["trace[1920009905] 'read index received' (duration: 102.863066ms)","trace[1920009905] 'applied index is now lower than readState.Index' (duration: 57.695201ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:28.079551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.679711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T21:52:28.079569Z","caller":"traceutil/trace.go:171","msg":"trace[1992354480] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:952; }","duration":"160.737612ms","start":"2026-03-19T21:52:27.918825Z","end":"2026-03-19T21:52:28.079562Z","steps":["trace[1992354480] 'agreement among raft nodes before linearized reading' (duration: 160.64661ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:28.079609Z","caller":"traceutil/trace.go:171","msg":"trace[1730858290] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"676.184113ms","start":"2026-03-19T21:52:27.403391Z","end":"2026-03-19T21:52:28.079576Z","steps":["trace[1730858290] 'process raft request' (duration: 675.989588ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:28.079663Z","caller":"traceutil/trace.go:171","msg":"trace[1952718812] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"536.048269ms","start":"2026-03-19T21:52:27.543594Z","end":"2026-03-19T21:52:28.079643Z","steps":["trace[1952718812] 'process raft request' (duration: 535.813624ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:28.079706Z","caller":"traceutil/trace.go:171","msg":"trace[1221288517] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"678.06008ms","start":"2026-03-19T21:52:27.401416Z","end":"2026-03-19T21:52:28.079476Z","steps":["trace[1221288517] 'process raft request' (duration: 677.901736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:28.079784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:27.543562Z","time spent":"536.148211ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T21:52:28.079815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:27.401386Z","time spent":"678.379028ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:28.079832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:27.403383Z","time spent":"676.338817ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:28.079911Z","caller":"traceutil/trace.go:171","msg":"trace[281580636] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"678.526702ms","start":"2026-03-19T21:52:27.401338Z","end":"2026-03-19T21:52:28.079865Z","steps":["trace[281580636] 'process raft request' (duration: 144.984176ms)","trace[281580636] 'compare' (duration: 532.664864ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:28.080137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:27.401321Z","time spent":"678.687947ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:52:29.556359Z","caller":"traceutil/trace.go:171","msg":"trace[145971192] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"151.593181ms","start":"2026-03-19T21:52:29.404735Z","end":"2026-03-19T21:52:29.556328Z","steps":["trace[145971192] 'process raft request' (duration: 151.180411ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:33.760417Z","caller":"traceutil/trace.go:171","msg":"trace[868055424] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"190.53119ms","start":"2026-03-19T21:52:33.569846Z","end":"2026-03-19T21:52:33.760377Z","steps":["trace[868055424] 'read index received' (duration: 190.196843ms)","trace[868055424] 'applied index is now lower than readState.Index' (duration: 332.897µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:33.76076Z","caller":"traceutil/trace.go:171","msg":"trace[365573165] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"357.066211ms","start":"2026-03-19T21:52:33.403667Z","end":"2026-03-19T21:52:33.760733Z","steps":["trace[365573165] 'process raft request' (duration: 356.516049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:33.761104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:33.403613Z","time spent":"357.180623ms","remote":"127.0.0.1:50176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5735,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:33.761136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.271537ms","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-03-19T21:52:33.761313Z","caller":"traceutil/trace.go:171","msg":"trace[614382531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"191.43468ms","start":"2026-03-19T21:52:33.569827Z","end":"2026-03-19T21:52:33.761261Z","steps":["trace[614382531] 'agreement among raft nodes before linearized reading' (duration: 191.146144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:33.761702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.047878ms","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-03-19T21:52:33.763979Z","caller":"traceutil/trace.go:171","msg":"trace[400879446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:995; }","duration":"182.320829ms","start":"2026-03-19T21:52:33.581574Z","end":"2026-03-19T21:52:33.763895Z","steps":["trace[400879446] 'agreement among raft nodes before linearized reading' (duration: 179.20065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:34.201087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.390737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:52:34.201391Z","caller":"traceutil/trace.go:171","msg":"trace[781017682] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"433.688135ms","start":"2026-03-19T21:52:33.767677Z","end":"2026-03-19T21:52:34.201365Z","steps":["trace[781017682] 'read index received' (duration: 90.611013ms)","trace[781017682] 'applied index is now lower than readState.Index' (duration: 343.075432ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:34.201422Z","caller":"traceutil/trace.go:171","msg":"trace[769625520] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"434.977204ms","start":"2026-03-19T21:52:33.766396Z","end":"2026-03-19T21:52:34.201373Z","steps":["trace[769625520] 'process raft request' (duration: 91.870882ms)","trace[769625520] 'compare' (duration: 342.145341ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:52:34.201557Z","caller":"traceutil/trace.go:171","msg":"trace[854426281] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"433.550882ms","start":"2026-03-19T21:52:33.767996Z","end":"2026-03-19T21:52:34.201547Z","steps":["trace[854426281] 'process raft request' (duration: 433.230075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:34.201592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:33.766375Z","time spent":"435.111347ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:34.201643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:33.767984Z","time spent":"433.609583ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:52:34.307929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.2178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:52:34.308046Z","caller":"traceutil/trace.go:171","msg":"trace[993403561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"540.369264ms","start":"2026-03-19T21:52:33.767652Z","end":"2026-03-19T21:52:34.308022Z","steps":["trace[993403561] 'agreement among raft nodes before linearized reading' (duration: 433.852889ms)","trace[993403561] 'range keys from in-memory index tree' (duration: 106.351971ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:34.308098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:52:33.767643Z","time spent":"540.442975ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T21:52:34.30791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.503984ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T21:52:34.308444Z","caller":"traceutil/trace.go:171","msg":"trace[30689465] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"106.947765ms","start":"2026-03-19T21:52:34.201463Z","end":"2026-03-19T21:52:34.308411Z","steps":["trace[30689465] 'read index received' (duration: 60.671µs)","trace[30689465] 'applied index is now lower than readState.Index' (duration: 106.884964ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:52:34.308656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.959323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-19T21:52:34.308679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.081376ms","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-03-19T21:52:34.308713Z","caller":"traceutil/trace.go:171","msg":"trace[869845979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"202.141137ms","start":"2026-03-19T21:52:34.106562Z","end":"2026-03-19T21:52:34.308703Z","steps":["trace[869845979] 'agreement among raft nodes before linearized reading' (duration: 201.985774ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:52:34.308707Z","caller":"traceutil/trace.go:171","msg":"trace[217125302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"206.081605ms","start":"2026-03-19T21:52:34.102612Z","end":"2026-03-19T21:52:34.308694Z","steps":["trace[217125302] 'agreement among raft nodes before linearized reading' (duration: 205.897051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:52:34.308963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.417344ms","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-03-19T21:52:34.308992Z","caller":"traceutil/trace.go:171","msg":"trace[831009304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:997; }","duration":"202.469085ms","start":"2026-03-19T21:52:34.106515Z","end":"2026-03-19T21:52:34.308984Z","steps":["trace[831009304] 'agreement among raft nodes before linearized reading' (duration: 202.287021ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:54:09.12382Z","caller":"traceutil/trace.go:171","msg":"trace[1479125223] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"106.602837ms","start":"2026-03-19T21:54:09.017192Z","end":"2026-03-19T21:54:09.123794Z","steps":["trace[1479125223] 'process raft request' (duration: 106.345531ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:54:10.999817Z","caller":"traceutil/trace.go:171","msg":"trace[132159167] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1469; }","duration":"108.994162ms","start":"2026-03-19T21:54:10.890799Z","end":"2026-03-19T21:54:10.999793Z","steps":["trace[132159167] 'read index received' (duration: 108.797457ms)","trace[132159167] 'applied index is now lower than readState.Index' (duration: 195.555µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:54:10.999865Z","caller":"traceutil/trace.go:171","msg":"trace[1870117204] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"181.952563ms","start":"2026-03-19T21:54:10.817888Z","end":"2026-03-19T21:54:10.99984Z","steps":["trace[1870117204] 'process raft request' (duration: 181.778059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:11.000008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.200016ms","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-03-19T21:54:11.000049Z","caller":"traceutil/trace.go:171","msg":"trace[778060863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1410; }","duration":"109.297658ms","start":"2026-03-19T21:54:10.89074Z","end":"2026-03-19T21:54:11.000037Z","steps":["trace[778060863] 'agreement among raft nodes before linearized reading' (duration: 109.150065ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:54:19.294099Z","caller":"traceutil/trace.go:171","msg":"trace[489545261] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"114.881725ms","start":"2026-03-19T21:54:19.179195Z","end":"2026-03-19T21:54:19.294076Z","steps":["trace[489545261] 'process raft request' (duration: 112.187703ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:54:21.406211Z","caller":"traceutil/trace.go:171","msg":"trace[1865121218] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1513; }","duration":"101.171213ms","start":"2026-03-19T21:54:21.305001Z","end":"2026-03-19T21:54:21.406172Z","steps":["trace[1865121218] 'read index received' (duration: 100.303334ms)","trace[1865121218] 'applied index is now lower than readState.Index' (duration: 866.099µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:54:21.406311Z","caller":"traceutil/trace.go:171","msg":"trace[613657088] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"103.655029ms","start":"2026-03-19T21:54:21.302615Z","end":"2026-03-19T21:54:21.40627Z","steps":["trace[613657088] 'process raft request' (duration: 102.666117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:21.406463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.447379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T21:54:21.406557Z","caller":"traceutil/trace.go:171","msg":"trace[1355689465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1452; }","duration":"101.592952ms","start":"2026-03-19T21:54:21.304941Z","end":"2026-03-19T21:54:21.406534Z","steps":["trace[1355689465] 'agreement among raft nodes before linearized reading' (duration: 101.368547ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:54:25.439295Z","caller":"traceutil/trace.go:171","msg":"trace[922630585] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"158.629001ms","start":"2026-03-19T21:54:25.280626Z","end":"2026-03-19T21:54:25.439255Z","steps":["trace[922630585] 'process raft request' (duration: 158.214632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:31.885849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.953007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:54:31.885922Z","caller":"traceutil/trace.go:171","msg":"trace[1260787053] linearizableReadLoop","detail":"{readStateIndex:1561; appliedIndex:1560; }","duration":"125.956908ms","start":"2026-03-19T21:54:31.759954Z","end":"2026-03-19T21:54:31.885911Z","steps":["trace[1260787053] 'read index received' (duration: 10.785796ms)","trace[1260787053] 'applied index is now lower than readState.Index' (duration: 115.170212ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:54:31.885979Z","caller":"traceutil/trace.go:171","msg":"trace[145239614] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"151.164091ms","start":"2026-03-19T21:54:31.734808Z","end":"2026-03-19T21:54:31.885972Z","steps":["trace[145239614] 'process raft request' (duration: 36.00041ms)","trace[145239614] 'compare' (duration: 114.783812ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:54:31.886125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.178982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-19T21:54:31.886139Z","caller":"traceutil/trace.go:171","msg":"trace[510344186] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1497; }","duration":"126.222524ms","start":"2026-03-19T21:54:31.759911Z","end":"2026-03-19T21:54:31.886133Z","steps":["trace[510344186] 'agreement among raft nodes before linearized reading' (duration: 126.135982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:54:31.886231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.578325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:54:31.886298Z","caller":"traceutil/trace.go:171","msg":"trace[2067429296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1497; }","duration":"120.698208ms","start":"2026-03-19T21:54:31.765583Z","end":"2026-03-19T21:54:31.886282Z","steps":["trace[2067429296] 'agreement among raft nodes before linearized reading' (duration: 120.582375ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:55:39.11312Z","caller":"traceutil/trace.go:171","msg":"trace[115803760] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"181.032977ms","start":"2026-03-19T21:55:38.932063Z","end":"2026-03-19T21:55:39.113096Z","steps":["trace[115803760] 'process raft request' (duration: 154.867426ms)","trace[115803760] 'compare' (duration: 26.043898ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:55:42.047001Z","caller":"traceutil/trace.go:171","msg":"trace[1886235836] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"149.762532ms","start":"2026-03-19T21:55:41.897192Z","end":"2026-03-19T21:55:42.046955Z","steps":["trace[1886235836] 'process raft request' (duration: 149.522277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:55:49.088165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.125215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:55:49.088239Z","caller":"traceutil/trace.go:171","msg":"trace[863022439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1843; }","duration":"320.252708ms","start":"2026-03-19T21:55:48.767969Z","end":"2026-03-19T21:55:49.088222Z","steps":["trace[863022439] 'range keys from in-memory index tree' (duration: 319.974412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:55:49.088849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.452885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-19T21:55:49.088905Z","caller":"traceutil/trace.go:171","msg":"trace[306011904] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1843; }","duration":"268.594198ms","start":"2026-03-19T21:55:48.820299Z","end":"2026-03-19T21:55:49.088893Z","steps":["trace[306011904] 'range keys from in-memory index tree' (duration: 268.273491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:55:49.089411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:55:48.767945Z","time spent":"321.452323ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}