{"level":"warn","ts":"2026-06-17T15:21:17.643668Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T15:21:17.643874Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.126: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.19.213.126:2380","--initial-cluster=instance=https://199.19.213.126:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.126:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.126: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-06-17T15:21:17.644065Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T15:21:17.644097Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.126:2380"]} {"level":"info","ts":"2026-06-17T15:21:17.644132Z","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-06-17T15:21:17.645287Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.126:2379"]} {"level":"info","ts":"2026-06-17T15:21:17.645434Z","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.19.213.126:2380"],"listen-peer-urls":["https://199.19.213.126:2380"],"advertise-client-urls":["https://199.19.213.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.126: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-06-17T15:21:17.650719Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.88042ms"} {"level":"info","ts":"2026-06-17T15:21:17.659791Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e241c9714ab70808","cluster-id":"e3245fbcce84a6b1"} {"level":"info","ts":"2026-06-17T15:21:17.659878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 switched to configuration voters=()"} {"level":"info","ts":"2026-06-17T15:21:17.659904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became follower at term 0"} {"level":"info","ts":"2026-06-17T15:21:17.659914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e241c9714ab70808 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-17T15:21:17.659921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became follower at term 1"} {"level":"info","ts":"2026-06-17T15:21:17.65996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 switched to configuration voters=(16303533614479902728)"} {"level":"warn","ts":"2026-06-17T15:21:17.668869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-17T15:21:17.673215Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-17T15:21:17.675581Z","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-06-17T15:21:17.679415Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e241c9714ab70808","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-17T15:21:17.680444Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e241c9714ab70808","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-17T15:21:17.680567Z","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-06-17T15:21:17.680606Z","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-06-17T15:21:17.680615Z","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-06-17T15:21:17.683386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 switched to configuration voters=(16303533614479902728)"} {"level":"info","ts":"2026-06-17T15:21:17.683614Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e3245fbcce84a6b1","local-member-id":"e241c9714ab70808","added-peer-id":"e241c9714ab70808","added-peer-peer-urls":["https://199.19.213.126:2380"]} {"level":"info","ts":"2026-06-17T15:21:17.687637Z","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-06-17T15:21:17.687844Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.126:2380"} {"level":"info","ts":"2026-06-17T15:21:17.687879Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.126:2380"} {"level":"info","ts":"2026-06-17T15:21:17.688028Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e241c9714ab70808","initial-advertise-peer-urls":["https://199.19.213.126:2380"],"listen-peer-urls":["https://199.19.213.126:2380"],"advertise-client-urls":["https://199.19.213.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-17T15:21:17.688095Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-17T15:21:18.26152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 is starting a new election at term 1"} {"level":"info","ts":"2026-06-17T15:21:18.261589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-17T15:21:18.261625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 received MsgPreVoteResp from e241c9714ab70808 at term 1"} {"level":"info","ts":"2026-06-17T15:21:18.261645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became candidate at term 2"} {"level":"info","ts":"2026-06-17T15:21:18.261655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 received MsgVoteResp from e241c9714ab70808 at term 2"} {"level":"info","ts":"2026-06-17T15:21:18.261671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became leader at term 2"} {"level":"info","ts":"2026-06-17T15:21:18.2617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e241c9714ab70808 elected leader e241c9714ab70808 at term 2"} {"level":"info","ts":"2026-06-17T15:21:18.263267Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:21:18.264942Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T15:21:18.264944Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e241c9714ab70808","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.126:2379]}","request-path":"/0/members/e241c9714ab70808/attributes","cluster-id":"e3245fbcce84a6b1","publish-timeout":"7s"} {"level":"info","ts":"2026-06-17T15:21:18.265109Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e3245fbcce84a6b1","local-member-id":"e241c9714ab70808","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:21:18.265137Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T15:21:18.265279Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:21:18.265316Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:21:18.266132Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-17T15:21:18.26618Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-17T15:21:18.26722Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.126:2379"} {"level":"info","ts":"2026-06-17T15:21:18.268509Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-17T15:21:42.227973Z","caller":"traceutil/trace.go:171","msg":"trace[785856041] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:441; }","duration":"224.094815ms","start":"2026-06-17T15:21:42.003843Z","end":"2026-06-17T15:21:42.227938Z","steps":["trace[785856041] 'read index received' (duration: 39.28276ms)","trace[785856041] 'applied index is now lower than readState.Index' (duration: 184.810495ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:21:42.228052Z","caller":"traceutil/trace.go:171","msg":"trace[1435523220] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"236.85908ms","start":"2026-06-17T15:21:41.991147Z","end":"2026-06-17T15:21:42.228006Z","steps":["trace[1435523220] 'process raft request' (duration: 236.574374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:42.228294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.420862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-17T15:21:42.228396Z","caller":"traceutil/trace.go:171","msg":"trace[1051463808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:430; }","duration":"224.580466ms","start":"2026-06-17T15:21:42.003789Z","end":"2026-06-17T15:21:42.228369Z","steps":["trace[1051463808] 'agreement among raft nodes before linearized reading' (duration: 224.367351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:42.228407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.391942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-17T15:21:42.228455Z","caller":"traceutil/trace.go:171","msg":"trace[1088391762] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:430; }","duration":"182.514114ms","start":"2026-06-17T15:21:42.045925Z","end":"2026-06-17T15:21:42.228439Z","steps":["trace[1088391762] 'agreement among raft nodes before linearized reading' (duration: 182.385962ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:42.380007Z","caller":"traceutil/trace.go:171","msg":"trace[1757055579] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"142.805956ms","start":"2026-06-17T15:21:42.237175Z","end":"2026-06-17T15:21:42.379981Z","steps":["trace[1757055579] 'process raft request' (duration: 141.765723ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:42.380491Z","caller":"traceutil/trace.go:171","msg":"trace[277480139] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"142.339306ms","start":"2026-06-17T15:21:42.238119Z","end":"2026-06-17T15:21:42.380458Z","steps":["trace[277480139] 'process raft request' (duration: 141.786383ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:56.48573Z","caller":"traceutil/trace.go:171","msg":"trace[739017341] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"359.909625ms","start":"2026-06-17T15:21:56.125795Z","end":"2026-06-17T15:21:56.485705Z","steps":["trace[739017341] 'process raft request' (duration: 359.773722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:56.486291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.125778Z","time spent":"360.038657ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:21:56.546666Z","caller":"traceutil/trace.go:171","msg":"trace[2098709285] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"420.571102ms","start":"2026-06-17T15:21:56.126044Z","end":"2026-06-17T15:21:56.546615Z","steps":["trace[2098709285] 'process raft request' (duration: 420.359406ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:56.546691Z","caller":"traceutil/trace.go:171","msg":"trace[1424138746] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"418.070937ms","start":"2026-06-17T15:21:56.12858Z","end":"2026-06-17T15:21:56.546651Z","steps":["trace[1424138746] 'process raft request' (duration: 417.945593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:56.546838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.126036Z","time spent":"420.729916ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:21:56.546842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.128574Z","time spent":"418.199099ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:21:56.546883Z","caller":"traceutil/trace.go:171","msg":"trace[331096807] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"379.617745ms","start":"2026-06-17T15:21:56.167185Z","end":"2026-06-17T15:21:56.546803Z","steps":["trace[331096807] 'process raft request' (duration: 379.487492ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:56.546914Z","caller":"traceutil/trace.go:171","msg":"trace[45975677] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"382.544241ms","start":"2026-06-17T15:21:56.164193Z","end":"2026-06-17T15:21:56.546737Z","steps":["trace[45975677] 'process raft request' (duration: 382.397786ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:56.547053Z","caller":"traceutil/trace.go:171","msg":"trace[499997088] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"360.18091ms","start":"2026-06-17T15:21:56.186862Z","end":"2026-06-17T15:21:56.547042Z","steps":["trace[499997088] 'process raft request' (duration: 359.969136ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:56.547054Z","caller":"traceutil/trace.go:171","msg":"trace[192147175] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"373.661512ms","start":"2026-06-17T15:21:56.173378Z","end":"2026-06-17T15:21:56.54704Z","steps":["trace[192147175] 'process raft request' (duration: 373.405757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:56.547078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.167181Z","time spent":"379.8078ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:21:56.547107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.186855Z","time spent":"360.230802ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:21:56.54724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.173369Z","time spent":"373.755695ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:21:56.546919Z","caller":"traceutil/trace.go:171","msg":"trace[1243110827] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"379.632685ms","start":"2026-06-17T15:21:56.167141Z","end":"2026-06-17T15:21:56.546774Z","steps":["trace[1243110827] 'process raft request' (duration: 379.494352ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:56.547283Z","caller":"traceutil/trace.go:171","msg":"trace[38018808] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:540; }","duration":"134.128651ms","start":"2026-06-17T15:21:56.413138Z","end":"2026-06-17T15:21:56.547266Z","steps":["trace[38018808] 'read index received' (duration: 72.513552ms)","trace[38018808] 'applied index is now lower than readState.Index' (duration: 61.613989ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:21:56.547324Z","caller":"traceutil/trace.go:171","msg":"trace[837429592] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"319.807276ms","start":"2026-06-17T15:21:56.227505Z","end":"2026-06-17T15:21:56.547312Z","steps":["trace[837429592] 'process raft request' (duration: 319.51497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:56.547336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.167134Z","time spent":"380.166508ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:21:56.547387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.227487Z","time spent":"319.874838ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:21:56.54722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.16415Z","time spent":"382.95152ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:21:56.547471Z","caller":"traceutil/trace.go:171","msg":"trace[1607152853] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"356.261582ms","start":"2026-06-17T15:21:56.191196Z","end":"2026-06-17T15:21:56.547457Z","steps":["trace[1607152853] 'process raft request' (duration: 355.69647ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:56.547484Z","caller":"traceutil/trace.go:171","msg":"trace[2103386100] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"202.868231ms","start":"2026-06-17T15:21:56.344607Z","end":"2026-06-17T15:21:56.547475Z","steps":["trace[2103386100] 'process raft request' (duration: 202.588505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:56.547549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.191185Z","time spent":"356.332324ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:21:56.547002Z","caller":"traceutil/trace.go:171","msg":"trace[311975737] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"379.400371ms","start":"2026-06-17T15:21:56.167589Z","end":"2026-06-17T15:21:56.54699Z","steps":["trace[311975737] 'process raft request' (duration: 379.119985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:56.547662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:56.167583Z","time spent":"380.059276ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:21:56.547758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.605293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-17T15:21:56.54781Z","caller":"traceutil/trace.go:171","msg":"trace[2129355819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"134.722345ms","start":"2026-06-17T15:21:56.413072Z","end":"2026-06-17T15:21:56.547795Z","steps":["trace[2129355819] 'agreement among raft nodes before linearized reading' (duration: 134.444409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:56.578587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.262683ms","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-06-17T15:21:56.578677Z","caller":"traceutil/trace.go:171","msg":"trace[366232709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"133.401186ms","start":"2026-06-17T15:21:56.445235Z","end":"2026-06-17T15:21:56.578636Z","steps":["trace[366232709] 'agreement among raft nodes before linearized reading' (duration: 111.538696ms)","trace[366232709] 'range keys from in-memory index tree' (duration: 21.637174ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:21:56.578877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.258382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-17T15:21:56.578918Z","caller":"traceutil/trace.go:171","msg":"trace[623242448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"133.308214ms","start":"2026-06-17T15:21:56.445596Z","end":"2026-06-17T15:21:56.578904Z","steps":["trace[623242448] 'agreement among raft nodes before linearized reading' (duration: 111.151078ms)","trace[623242448] 'range keys from in-memory index tree' (duration: 22.084165ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:21:58.383387Z","caller":"traceutil/trace.go:171","msg":"trace[1607779085] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"219.423469ms","start":"2026-06-17T15:21:58.163929Z","end":"2026-06-17T15:21:58.383353Z","steps":["trace[1607779085] 'process raft request' (duration: 219.095282ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:58.387632Z","caller":"traceutil/trace.go:171","msg":"trace[1917430335] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"215.810139ms","start":"2026-06-17T15:21:58.171736Z","end":"2026-06-17T15:21:58.387546Z","steps":["trace[1917430335] 'read index received' (duration: 211.174575ms)","trace[1917430335] 'applied index is now lower than readState.Index' (duration: 4.634084ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:21:58.387978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.214719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-06-17T15:21:58.388029Z","caller":"traceutil/trace.go:171","msg":"trace[1847503774] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:601; }","duration":"216.2946ms","start":"2026-06-17T15:21:58.17172Z","end":"2026-06-17T15:21:58.388014Z","steps":["trace[1847503774] 'agreement among raft nodes before linearized reading' (duration: 216.021874ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:58.388306Z","caller":"traceutil/trace.go:171","msg":"trace[109932586] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"220.429143ms","start":"2026-06-17T15:21:58.16783Z","end":"2026-06-17T15:21:58.388259Z","steps":["trace[109932586] 'process raft request' (duration: 219.538753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:04.109102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578887194817880458,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-17T15:22:04.137514Z","caller":"traceutil/trace.go:171","msg":"trace[136176736] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"554.509368ms","start":"2026-06-17T15:22:03.582978Z","end":"2026-06-17T15:22:04.137488Z","steps":["trace[136176736] 'process raft request' (duration: 554.340734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:04.138036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:03.582958Z","time spent":"554.721463ms","remote":"127.0.0.1:43308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:22:05.323363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.100231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:22:05.323505Z","caller":"traceutil/trace.go:171","msg":"trace[108444747] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.629643818s","start":"2026-06-17T15:22:03.693848Z","end":"2026-06-17T15:22:05.323492Z","steps":["trace[108444747] 'process raft request' (duration: 679.244829ms)","trace[108444747] 'compare' (duration: 943.361431ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:22:05.323548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:03.693802Z","time spent":"1.62972897s","remote":"127.0.0.1:43206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:22:05.323623Z","caller":"traceutil/trace.go:171","msg":"trace[2081699772] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"1.71462404s","start":"2026-06-17T15:22:03.608904Z","end":"2026-06-17T15:22:05.323528Z","steps":["trace[2081699772] 'read index received' (duration: 528.416794ms)","trace[2081699772] 'applied index is now lower than readState.Index' (duration: 1.186201206s)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:22:05.323937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.503242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-17T15:22:05.323974Z","caller":"traceutil/trace.go:171","msg":"trace[346522797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"698.588873ms","start":"2026-06-17T15:22:04.625372Z","end":"2026-06-17T15:22:05.323961Z","steps":["trace[346522797] 'agreement among raft nodes before linearized reading' (duration: 698.497221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:05.323992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:04.625336Z","time spent":"698.652875ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-17T15:22:05.324105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.608742ms","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-06-17T15:22:05.324163Z","caller":"traceutil/trace.go:171","msg":"trace[1458638948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"689.704444ms","start":"2026-06-17T15:22:04.634415Z","end":"2026-06-17T15:22:05.32412Z","steps":["trace[1458638948] 'agreement among raft nodes before linearized reading' (duration: 689.660013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:05.324181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:04.634383Z","time spent":"689.794365ms","remote":"127.0.0.1:43376","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-06-17T15:22:05.324171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.514096ms","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-06-17T15:22:05.324223Z","caller":"traceutil/trace.go:171","msg":"trace[893234941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"683.582777ms","start":"2026-06-17T15:22:04.640622Z","end":"2026-06-17T15:22:05.324205Z","steps":["trace[893234941] 'agreement among raft nodes before linearized reading' (duration: 683.437174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:05.324261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:04.640607Z","time spent":"683.646708ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-17T15:22:05.324278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.857017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-17T15:22:05.324298Z","caller":"traceutil/trace.go:171","msg":"trace[760406593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"689.911378ms","start":"2026-06-17T15:22:04.634383Z","end":"2026-06-17T15:22:05.324294Z","steps":["trace[760406593] 'agreement among raft nodes before linearized reading' (duration: 689.862787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:05.324315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:04.634354Z","time spent":"689.957809ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-17T15:22:05.324269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.715321835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:22:05.324376Z","caller":"traceutil/trace.go:171","msg":"trace[1275313674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"1.715460908s","start":"2026-06-17T15:22:03.608883Z","end":"2026-06-17T15:22:05.324344Z","steps":["trace[1275313674] 'agreement among raft nodes before linearized reading' (duration: 1.714875225s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:05.324436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:03.608794Z","time spent":"1.715631863s","remote":"127.0.0.1:43092","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-06-17T15:22:05.324442Z","caller":"traceutil/trace.go:171","msg":"trace[1346968369] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"799.037591ms","start":"2026-06-17T15:22:04.525397Z","end":"2026-06-17T15:22:05.324435Z","steps":["trace[1346968369] 'process raft request' (duration: 798.048629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:05.324542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:04.52537Z","time spent":"799.151393ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:22:20.824225Z","caller":"traceutil/trace.go:171","msg":"trace[550449355] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"134.774886ms","start":"2026-06-17T15:22:20.689089Z","end":"2026-06-17T15:22:20.823864Z","steps":["trace[550449355] 'process raft request' (duration: 134.520261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:56.583245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.834806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:22:56.583381Z","caller":"traceutil/trace.go:171","msg":"trace[2070950536] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"786.214075ms","start":"2026-06-17T15:22:55.797143Z","end":"2026-06-17T15:22:56.583358Z","steps":["trace[2070950536] 'process raft request' (duration: 597.101303ms)","trace[2070950536] 'compare' (duration: 188.288133ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:22:56.583436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:55.79712Z","time spent":"786.291986ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:22:57.177494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.058003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:22:57.177656Z","caller":"traceutil/trace.go:171","msg":"trace[1352054634] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"788.297342ms","start":"2026-06-17T15:22:56.389332Z","end":"2026-06-17T15:22:57.177629Z","steps":["trace[1352054634] 'process raft request' (duration: 387.988193ms)","trace[1352054634] 'compare' (duration: 399.302336ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:22:57.177732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:56.389311Z","time spent":"788.390944ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:22:57.511758Z","caller":"traceutil/trace.go:171","msg":"trace[170271649] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"478.537328ms","start":"2026-06-17T15:22:57.033182Z","end":"2026-06-17T15:22:57.511719Z","steps":["trace[170271649] 'read index received' (duration: 418.54195ms)","trace[170271649] 'applied index is now lower than readState.Index' (duration: 59.993748ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:22:57.512014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.817804ms","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-06-17T15:22:57.512047Z","caller":"traceutil/trace.go:171","msg":"trace[1711434440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"478.932596ms","start":"2026-06-17T15:22:57.033104Z","end":"2026-06-17T15:22:57.512037Z","steps":["trace[1711434440] 'agreement among raft nodes before linearized reading' (duration: 478.729841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:57.512074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:57.033072Z","time spent":"478.994698ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-17T15:23:27.686875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.101687ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-17T15:23:28.954465Z","caller":"traceutil/trace.go:171","msg":"trace[1744002667] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"215.198828ms","start":"2026-06-17T15:23:28.739202Z","end":"2026-06-17T15:23:28.954401Z","steps":["trace[1744002667] 'process raft request' (duration: 215.010374ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:30.260399Z","caller":"traceutil/trace.go:171","msg":"trace[1813906129] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"413.171859ms","start":"2026-06-17T15:23:29.84718Z","end":"2026-06-17T15:23:30.260352Z","steps":["trace[1813906129] 'process raft request' (duration: 412.897133ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:30.260404Z","caller":"traceutil/trace.go:171","msg":"trace[942397097] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"367.37025ms","start":"2026-06-17T15:23:29.892916Z","end":"2026-06-17T15:23:30.260286Z","steps":["trace[942397097] 'read index received' (duration: 367.181835ms)","trace[942397097] 'applied index is now lower than readState.Index' (duration: 186.475µs)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:23:30.260596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:29.847143Z","time spent":"413.348603ms","remote":"127.0.0.1:43376","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-06-17T15:23:30.260719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.758178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-17T15:23:30.260853Z","caller":"traceutil/trace.go:171","msg":"trace[1648359277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1127; }","duration":"367.963613ms","start":"2026-06-17T15:23:29.892856Z","end":"2026-06-17T15:23:30.260819Z","steps":["trace[1648359277] 'agreement among raft nodes before linearized reading' (duration: 367.581695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:30.260895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.200443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-17T15:23:30.260918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:29.892829Z","time spent":"368.079625ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-17T15:23:30.260955Z","caller":"traceutil/trace.go:171","msg":"trace[29012833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1127; }","duration":"350.352877ms","start":"2026-06-17T15:23:29.910586Z","end":"2026-06-17T15:23:30.260939Z","steps":["trace[29012833] 'agreement among raft nodes before linearized reading' (duration: 350.09148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:30.260848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.787979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T15:23:30.261009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:29.910556Z","time spent":"350.441719ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-17T15:23:30.261035Z","caller":"traceutil/trace.go:171","msg":"trace[33075798] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1127; }","duration":"368.093157ms","start":"2026-06-17T15:23:29.89291Z","end":"2026-06-17T15:23:30.261003Z","steps":["trace[33075798] 'agreement among raft nodes before linearized reading' (duration: 367.735028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:30.261093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.772114ms","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-06-17T15:23:30.261175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:29.892882Z","time spent":"368.24387ms","remote":"127.0.0.1:37326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-06-17T15:23:30.261261Z","caller":"traceutil/trace.go:171","msg":"trace[428524699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1127; }","duration":"353.955959ms","start":"2026-06-17T15:23:29.907237Z","end":"2026-06-17T15:23:30.261193Z","steps":["trace[428524699] 'agreement among raft nodes before linearized reading' (duration: 353.476657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:30.261333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:29.907226Z","time spent":"354.095072ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-17T15:23:30.409074Z","caller":"traceutil/trace.go:171","msg":"trace[1326989641] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"142.546919ms","start":"2026-06-17T15:23:30.266483Z","end":"2026-06-17T15:23:30.40903Z","steps":["trace[1326989641] 'process raft request' (duration: 97.231899ms)","trace[1326989641] 'compare' (duration: 44.938082ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:23:30.409888Z","caller":"traceutil/trace.go:171","msg":"trace[1602540832] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"142.627121ms","start":"2026-06-17T15:23:30.267213Z","end":"2026-06-17T15:23:30.40984Z","steps":["trace[1602540832] 'process raft request' (duration: 141.762671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:30.959352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.353153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:23:30.959487Z","caller":"traceutil/trace.go:171","msg":"trace[2135604461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1130; }","duration":"352.572328ms","start":"2026-06-17T15:23:30.606882Z","end":"2026-06-17T15:23:30.959454Z","steps":["trace[2135604461] 'range keys from in-memory index tree' (duration: 352.22053ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:30.959646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:30.606852Z","time spent":"352.771592ms","remote":"127.0.0.1:43094","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-06-17T15:23:30.959676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.105925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:23:30.959758Z","caller":"traceutil/trace.go:171","msg":"trace[1063963742] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1130; }","duration":"204.259619ms","start":"2026-06-17T15:23:30.755478Z","end":"2026-06-17T15:23:30.959738Z","steps":["trace[1063963742] 'count revisions from in-memory index tree' (duration: 204.012973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:52.565181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.558814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:23:52.565467Z","caller":"traceutil/trace.go:171","msg":"trace[1678267315] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1296; }","duration":"178.241123ms","start":"2026-06-17T15:23:52.387203Z","end":"2026-06-17T15:23:52.565444Z","steps":["trace[1678267315] 'read index received' (duration: 172.396271ms)","trace[1678267315] 'applied index is now lower than readState.Index' (duration: 5.843062ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:23:52.565606Z","caller":"traceutil/trace.go:171","msg":"trace[815759326] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"676.645514ms","start":"2026-06-17T15:23:51.888943Z","end":"2026-06-17T15:23:52.565589Z","steps":["trace[815759326] 'process raft request' (duration: 336.597228ms)","trace[815759326] 'compare' (duration: 339.444662ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:23:52.565688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:51.888927Z","time spent":"676.723386ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:23:52.566149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.857037ms","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-06-17T15:23:52.566175Z","caller":"traceutil/trace.go:171","msg":"trace[1896259995] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"673.873682ms","start":"2026-06-17T15:23:51.89228Z","end":"2026-06-17T15:23:52.566154Z","steps":["trace[1896259995] 'process raft request' (duration: 673.050883ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:52.566214Z","caller":"traceutil/trace.go:171","msg":"trace[1109879618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"179.0021ms","start":"2026-06-17T15:23:52.387196Z","end":"2026-06-17T15:23:52.566198Z","steps":["trace[1109879618] 'agreement among raft nodes before linearized reading' (duration: 178.783395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:52.566198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.967249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-17T15:23:52.566282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:51.892258Z","time spent":"673.969364ms","remote":"127.0.0.1:43376","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-06-17T15:23:52.566328Z","caller":"traceutil/trace.go:171","msg":"trace[150905722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"179.204725ms","start":"2026-06-17T15:23:52.387087Z","end":"2026-06-17T15:23:52.566292Z","steps":["trace[150905722] 'agreement among raft nodes before linearized reading' (duration: 178.771755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:04.830943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.783305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:24:04.831296Z","caller":"traceutil/trace.go:171","msg":"trace[1388646462] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"154.931537ms","start":"2026-06-17T15:24:04.676307Z","end":"2026-06-17T15:24:04.831238Z","steps":["trace[1388646462] 'process raft request' (duration: 19.760804ms)","trace[1388646462] 'compare' (duration: 134.625761ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:04.831405Z","caller":"traceutil/trace.go:171","msg":"trace[1787685986] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"154.959499ms","start":"2026-06-17T15:24:04.676429Z","end":"2026-06-17T15:24:04.831388Z","steps":["trace[1787685986] 'process raft request' (duration: 154.779895ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:04.832256Z","caller":"traceutil/trace.go:171","msg":"trace[1564432296] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"154.921437ms","start":"2026-06-17T15:24:04.676411Z","end":"2026-06-17T15:24:04.831332Z","steps":["trace[1564432296] 'process raft request' (duration: 154.732383ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:04.832764Z","caller":"traceutil/trace.go:171","msg":"trace[715989158] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"156.260018ms","start":"2026-06-17T15:24:04.676488Z","end":"2026-06-17T15:24:04.832748Z","steps":["trace[715989158] 'process raft request' (duration: 154.759704ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:09.425079Z","caller":"traceutil/trace.go:171","msg":"trace[65852105] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"310.44713ms","start":"2026-06-17T15:24:09.114592Z","end":"2026-06-17T15:24:09.425039Z","steps":["trace[65852105] 'process raft request' (duration: 310.240785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:09.425365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:09.11457Z","time spent":"310.608293ms","remote":"127.0.0.1:43308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:09.546745Z","caller":"traceutil/trace.go:171","msg":"trace[310000080] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"402.352088ms","start":"2026-06-17T15:24:09.144366Z","end":"2026-06-17T15:24:09.546718Z","steps":["trace[310000080] 'process raft request' (duration: 348.049886ms)","trace[310000080] 'compare' (duration: 54.18969ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:09.547011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:09.144327Z","time spent":"402.485911ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:10.270381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.665177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:24:10.270682Z","caller":"traceutil/trace.go:171","msg":"trace[1542541464] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1376; }","duration":"681.554335ms","start":"2026-06-17T15:24:09.58911Z","end":"2026-06-17T15:24:10.270664Z","steps":["trace[1542541464] 'read index received' (duration: 349.524979ms)","trace[1542541464] 'applied index is now lower than readState.Index' (duration: 332.028286ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:10.270938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.759267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T15:24:10.270933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.79834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:10.270977Z","caller":"traceutil/trace.go:171","msg":"trace[2050659064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"664.831007ms","start":"2026-06-17T15:24:09.606137Z","end":"2026-06-17T15:24:10.270968Z","steps":["trace[2050659064] 'agreement among raft nodes before linearized reading' (duration: 664.768486ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:10.271009Z","caller":"traceutil/trace.go:171","msg":"trace[1917109329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"681.917793ms","start":"2026-06-17T15:24:09.589073Z","end":"2026-06-17T15:24:10.270991Z","steps":["trace[1917109329] 'agreement among raft nodes before linearized reading' (duration: 681.78367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:10.271016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:09.606114Z","time spent":"664.894659ms","remote":"127.0.0.1:43092","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-06-17T15:24:10.27106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:09.589054Z","time spent":"681.998504ms","remote":"127.0.0.1:43094","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-06-17T15:24:10.271276Z","caller":"traceutil/trace.go:171","msg":"trace[320565949] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"839.479279ms","start":"2026-06-17T15:24:09.431714Z","end":"2026-06-17T15:24:10.271193Z","steps":["trace[320565949] 'process raft request' (duration: 506.919832ms)","trace[320565949] 'compare' (duration: 331.537375ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:10.271253Z","caller":"traceutil/trace.go:171","msg":"trace[1997785782] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"835.431338ms","start":"2026-06-17T15:24:09.435745Z","end":"2026-06-17T15:24:10.271176Z","steps":["trace[1997785782] 'process raft request' (duration: 834.841665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:10.271351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:09.431691Z","time spent":"839.624853ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:10.271317Z","caller":"traceutil/trace.go:171","msg":"trace[627444232] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"839.416758ms","start":"2026-06-17T15:24:09.431857Z","end":"2026-06-17T15:24:10.271274Z","steps":["trace[627444232] 'process raft request' (duration: 838.648181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:10.271515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:09.431851Z","time spent":"839.599502ms","remote":"127.0.0.1:43390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:10.271606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:09.435737Z","time spent":"835.731285ms","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:10.981697Z","caller":"traceutil/trace.go:171","msg":"trace[1966257047] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"117.545376ms","start":"2026-06-17T15:24:10.864124Z","end":"2026-06-17T15:24:10.981669Z","steps":["trace[1966257047] 'process raft request' (duration: 117.404142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:10.981949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.764732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-17T15:24:10.982012Z","caller":"traceutil/trace.go:171","msg":"trace[1742875194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1331; }","duration":"117.888554ms","start":"2026-06-17T15:24:10.864108Z","end":"2026-06-17T15:24:10.981997Z","steps":["trace[1742875194] 'agreement among raft nodes before linearized reading' (duration: 117.622288ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:11.085302Z","caller":"traceutil/trace.go:171","msg":"trace[1564149022] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"131.798867ms","start":"2026-06-17T15:24:10.953485Z","end":"2026-06-17T15:24:11.085284Z","steps":["trace[1564149022] 'process raft request' (duration: 131.680384ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:11.10201Z","caller":"traceutil/trace.go:171","msg":"trace[859362582] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"116.680926ms","start":"2026-06-17T15:24:10.985303Z","end":"2026-06-17T15:24:11.101984Z","steps":["trace[859362582] 'process raft request' (duration: 116.510622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:12.031961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.508887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:24:12.032096Z","caller":"traceutil/trace.go:171","msg":"trace[1159302010] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"425.037348ms","start":"2026-06-17T15:24:11.607041Z","end":"2026-06-17T15:24:12.032078Z","steps":["trace[1159302010] 'read index received' (duration: 47.861µs)","trace[1159302010] 'applied index is now lower than readState.Index' (duration: 424.987797ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:12.032157Z","caller":"traceutil/trace.go:171","msg":"trace[1776909782] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"480.425725ms","start":"2026-06-17T15:24:11.551691Z","end":"2026-06-17T15:24:12.032117Z","steps":["trace[1776909782] 'compare' (duration: 472.069268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:12.032237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.192653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:12.032267Z","caller":"traceutil/trace.go:171","msg":"trace[1087576223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"425.261844ms","start":"2026-06-17T15:24:11.606996Z","end":"2026-06-17T15:24:12.032258Z","steps":["trace[1087576223] 'agreement among raft nodes before linearized reading' (duration: 425.197693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:12.032312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:11.606964Z","time spent":"425.340036ms","remote":"127.0.0.1:43094","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-06-17T15:24:12.032376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:11.551668Z","time spent":"480.5836ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:12.032636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.659467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.126\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-17T15:24:12.032677Z","caller":"traceutil/trace.go:171","msg":"trace[431268014] range","detail":"{range_begin:/registry/masterleases/199.19.213.126; range_end:; response_count:1; response_revision:1334; }","duration":"295.759559ms","start":"2026-06-17T15:24:11.736909Z","end":"2026-06-17T15:24:12.032668Z","steps":["trace[431268014] 'agreement among raft nodes before linearized reading' (duration: 295.618935ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:12.563372Z","caller":"traceutil/trace.go:171","msg":"trace[684681147] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"525.723576ms","start":"2026-06-17T15:24:12.037607Z","end":"2026-06-17T15:24:12.563331Z","steps":["trace[684681147] 'process raft request' (duration: 465.05041ms)","trace[684681147] 'compare' (duration: 60.486462ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:12.563576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:12.037573Z","time spent":"525.89999ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:12.851362Z","caller":"traceutil/trace.go:171","msg":"trace[994904601] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"101.994376ms","start":"2026-06-17T15:24:12.749343Z","end":"2026-06-17T15:24:12.851337Z","steps":["trace[994904601] 'process raft request' (duration: 101.854573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:13.08672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.116117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-17T15:24:13.086776Z","caller":"traceutil/trace.go:171","msg":"trace[1310991630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1336; }","duration":"133.207339ms","start":"2026-06-17T15:24:12.953556Z","end":"2026-06-17T15:24:13.086763Z","steps":["trace[1310991630] 'range keys from in-memory index tree' (duration: 133.003775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:13.08672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.452974ms","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-06-17T15:24:13.086919Z","caller":"traceutil/trace.go:171","msg":"trace[1872844219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"101.692919ms","start":"2026-06-17T15:24:12.985213Z","end":"2026-06-17T15:24:13.086906Z","steps":["trace[1872844219] 'range keys from in-memory index tree' (duration: 101.28497ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:13.804389Z","caller":"traceutil/trace.go:171","msg":"trace[883045271] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1388; }","duration":"697.82874ms","start":"2026-06-17T15:24:13.106524Z","end":"2026-06-17T15:24:13.804353Z","steps":["trace[883045271] 'read index received' (duration: 42.873475ms)","trace[883045271] 'applied index is now lower than readState.Index' (duration: 654.953715ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:13.804467Z","caller":"traceutil/trace.go:171","msg":"trace[2066485289] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"709.970633ms","start":"2026-06-17T15:24:13.094472Z","end":"2026-06-17T15:24:13.804443Z","steps":["trace[2066485289] 'process raft request' (duration: 709.8243ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:13.804537Z","caller":"traceutil/trace.go:171","msg":"trace[1210355537] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"712.872389ms","start":"2026-06-17T15:24:13.091505Z","end":"2026-06-17T15:24:13.804377Z","steps":["trace[1210355537] 'process raft request' (duration: 712.589603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:13.80506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:13.094458Z","time spent":"710.501315ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:13.805168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:13.091495Z","time spent":"713.551704ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:13.804722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.169349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-17T15:24:13.80528Z","caller":"traceutil/trace.go:171","msg":"trace[1242024514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1339; }","duration":"698.775712ms","start":"2026-06-17T15:24:13.106464Z","end":"2026-06-17T15:24:13.80524Z","steps":["trace[1242024514] 'agreement among raft nodes before linearized reading' (duration: 698.066996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:13.805413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:13.106417Z","time spent":"698.953776ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-17T15:24:14.34479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.352786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:24:14.345035Z","caller":"traceutil/trace.go:171","msg":"trace[1188853751] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1391; }","duration":"540.134921ms","start":"2026-06-17T15:24:13.804879Z","end":"2026-06-17T15:24:14.345014Z","steps":["trace[1188853751] 'read index received' (duration: 353.437157ms)","trace[1188853751] 'applied index is now lower than readState.Index' (duration: 186.696614ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:14.345181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.57845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:14.345226Z","caller":"traceutil/trace.go:171","msg":"trace[1374920632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"739.667812ms","start":"2026-06-17T15:24:13.605547Z","end":"2026-06-17T15:24:14.345215Z","steps":["trace[1374920632] 'agreement among raft nodes before linearized reading' (duration: 739.56084ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:14.34524Z","caller":"traceutil/trace.go:171","msg":"trace[1365815747] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"1.07360211s","start":"2026-06-17T15:24:13.271625Z","end":"2026-06-17T15:24:14.345227Z","steps":["trace[1365815747] 'process raft request' (duration: 886.672672ms)","trace[1365815747] 'compare' (duration: 185.800433ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:14.345264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:13.605522Z","time spent":"739.732504ms","remote":"127.0.0.1:43094","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-06-17T15:24:14.345379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:13.271591Z","time spent":"1.073689393s","remote":"127.0.0.1:43376","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-06-17T15:24:14.345145Z","caller":"traceutil/trace.go:171","msg":"trace[784321852] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"1.052592578s","start":"2026-06-17T15:24:13.292517Z","end":"2026-06-17T15:24:14.345109Z","steps":["trace[784321852] 'process raft request' (duration: 1.052412734s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:14.345544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:13.292485Z","time spent":"1.052991517s","remote":"127.0.0.1:43192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:24:14.346884Z","caller":"traceutil/trace.go:171","msg":"trace[1285595949] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"535.70278ms","start":"2026-06-17T15:24:13.811144Z","end":"2026-06-17T15:24:14.346847Z","steps":["trace[1285595949] 'process raft request' (duration: 535.545347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:14.347098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:13.811115Z","time spent":"535.854624ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:14.347283Z","caller":"traceutil/trace.go:171","msg":"trace[1183640228] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"308.526846ms","start":"2026-06-17T15:24:14.038357Z","end":"2026-06-17T15:24:14.346884Z","steps":["trace[1183640228] 'process raft request' (duration: 308.408743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:14.347378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.080421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-17T15:24:14.347401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:14.038313Z","time spent":"309.033807ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:14.347437Z","caller":"traceutil/trace.go:171","msg":"trace[80533519] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1343; }","duration":"246.143542ms","start":"2026-06-17T15:24:14.101281Z","end":"2026-06-17T15:24:14.347425Z","steps":["trace[80533519] 'agreement among raft nodes before linearized reading' (duration: 246.04605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:14.347435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.459032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:14.347478Z","caller":"traceutil/trace.go:171","msg":"trace[289405320] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1343; }","duration":"239.530773ms","start":"2026-06-17T15:24:14.107935Z","end":"2026-06-17T15:24:14.347466Z","steps":["trace[289405320] 'agreement among raft nodes before linearized reading' (duration: 239.448531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:14.760353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.907325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:14.760446Z","caller":"traceutil/trace.go:171","msg":"trace[1323170715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1343; }","duration":"154.047559ms","start":"2026-06-17T15:24:14.606377Z","end":"2026-06-17T15:24:14.760424Z","steps":["trace[1323170715] 'range keys from in-memory index tree' (duration: 153.797832ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:20.102979Z","caller":"traceutil/trace.go:171","msg":"trace[1859645034] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"265.57948ms","start":"2026-06-17T15:24:19.837363Z","end":"2026-06-17T15:24:20.102942Z","steps":["trace[1859645034] 'process raft request' (duration: 265.310503ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:20.750533Z","caller":"traceutil/trace.go:171","msg":"trace[1276926322] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"368.507836ms","start":"2026-06-17T15:24:20.38199Z","end":"2026-06-17T15:24:20.750498Z","steps":["trace[1276926322] 'process raft request' (duration: 368.410224ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:20.750629Z","caller":"traceutil/trace.go:171","msg":"trace[468569447] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"911.628654ms","start":"2026-06-17T15:24:19.838966Z","end":"2026-06-17T15:24:20.750594Z","steps":["trace[468569447] 'process raft request' (duration: 892.063614ms)","trace[468569447] 'compare' (duration: 19.202202ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:20.750921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.143199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:110430"} {"level":"info","ts":"2026-06-17T15:24:20.750967Z","caller":"traceutil/trace.go:171","msg":"trace[1847573880] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1381; }","duration":"790.248191ms","start":"2026-06-17T15:24:19.960708Z","end":"2026-06-17T15:24:20.750956Z","steps":["trace[1847573880] 'agreement among raft nodes before linearized reading' (duration: 790.027576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:20.751013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:19.960687Z","time spent":"790.317782ms","remote":"127.0.0.1:43180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":110454,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-06-17T15:24:20.75102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:19.838951Z","time spent":"911.947491ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:20.750683Z","caller":"traceutil/trace.go:171","msg":"trace[381145345] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1431; }","duration":"789.898582ms","start":"2026-06-17T15:24:19.960757Z","end":"2026-06-17T15:24:20.750655Z","steps":["trace[381145345] 'read index received' (duration: 142.066107ms)","trace[381145345] 'applied index is now lower than readState.Index' (duration: 647.829265ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:20.751277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.437695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-06-17T15:24:20.751347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.171813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:20.751436Z","caller":"traceutil/trace.go:171","msg":"trace[1598733497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1381; }","duration":"147.318337ms","start":"2026-06-17T15:24:20.604097Z","end":"2026-06-17T15:24:20.751416Z","steps":["trace[1598733497] 'agreement among raft nodes before linearized reading' (duration: 147.177574ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:20.751378Z","caller":"traceutil/trace.go:171","msg":"trace[1781805103] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1381; }","duration":"790.617659ms","start":"2026-06-17T15:24:19.960746Z","end":"2026-06-17T15:24:20.751363Z","steps":["trace[1781805103] 'agreement among raft nodes before linearized reading' (duration: 790.450995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:20.751537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:19.960708Z","time spent":"790.819374ms","remote":"127.0.0.1:43186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-06-17T15:24:20.751388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.629906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-17T15:24:20.751675Z","caller":"traceutil/trace.go:171","msg":"trace[796776102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1381; }","duration":"347.968444ms","start":"2026-06-17T15:24:20.403694Z","end":"2026-06-17T15:24:20.751663Z","steps":["trace[796776102] 'agreement among raft nodes before linearized reading' (duration: 347.589625ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:20.7517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:20.403662Z","time spent":"348.032545ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-17T15:24:20.751813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.336477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-06-17T15:24:20.752042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:20.381945Z","time spent":"368.832993ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:20.75189Z","caller":"traceutil/trace.go:171","msg":"trace[1557421620] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1381; }","duration":"788.469191ms","start":"2026-06-17T15:24:19.963409Z","end":"2026-06-17T15:24:20.751878Z","steps":["trace[1557421620] 'agreement among raft nodes before linearized reading' (duration: 787.99207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:20.753079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:19.963205Z","time spent":"789.856352ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-17T15:24:22.258555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.196793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-17T15:24:22.258643Z","caller":"traceutil/trace.go:171","msg":"trace[1905577604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1395; }","duration":"151.361588ms","start":"2026-06-17T15:24:22.10726Z","end":"2026-06-17T15:24:22.258622Z","steps":["trace[1905577604] 'range keys from in-memory index tree' (duration: 150.856186ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:28:12.154357Z","caller":"traceutil/trace.go:171","msg":"trace[1354161412] linearizableReadLoop","detail":"{readStateIndex:2587; appliedIndex:2586; }","duration":"140.924061ms","start":"2026-06-17T15:28:12.013407Z","end":"2026-06-17T15:28:12.154331Z","steps":["trace[1354161412] 'read index received' (duration: 140.703656ms)","trace[1354161412] 'applied index is now lower than readState.Index' (duration: 219.295µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:28:12.154425Z","caller":"traceutil/trace.go:171","msg":"trace[1897481743] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"144.92759ms","start":"2026-06-17T15:28:12.00947Z","end":"2026-06-17T15:28:12.154397Z","steps":["trace[1897481743] 'process raft request' (duration: 144.687285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:28:12.154704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.27628ms","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-06-17T15:28:12.154791Z","caller":"traceutil/trace.go:171","msg":"trace[1461717014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2486; }","duration":"141.411323ms","start":"2026-06-17T15:28:12.013359Z","end":"2026-06-17T15:28:12.15477Z","steps":["trace[1461717014] 'agreement among raft nodes before linearized reading' (duration: 141.157326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:28:12.373221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.31587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:28:12.373546Z","caller":"traceutil/trace.go:171","msg":"trace[1003100626] linearizableReadLoop","detail":"{readStateIndex:2589; appliedIndex:2587; }","duration":"179.336994ms","start":"2026-06-17T15:28:12.194107Z","end":"2026-06-17T15:28:12.373444Z","steps":["trace[1003100626] 'read index received' (duration: 78.833429ms)","trace[1003100626] 'applied index is now lower than readState.Index' (duration: 100.502515ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:28:12.373734Z","caller":"traceutil/trace.go:171","msg":"trace[1469681261] transaction","detail":"{read_only:false; response_revision:2487; number_of_response:1; }","duration":"214.84064ms","start":"2026-06-17T15:28:12.158849Z","end":"2026-06-17T15:28:12.37369Z","steps":["trace[1469681261] 'process raft request' (duration: 113.936667ms)","trace[1469681261] 'compare' (duration: 100.166566ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:28:12.373869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.734902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:28:12.37407Z","caller":"traceutil/trace.go:171","msg":"trace[638663375] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2488; }","duration":"179.947287ms","start":"2026-06-17T15:28:12.194063Z","end":"2026-06-17T15:28:12.37401Z","steps":["trace[638663375] 'agreement among raft nodes before linearized reading' (duration: 179.572359ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:28:12.374122Z","caller":"traceutil/trace.go:171","msg":"trace[245648871] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"214.107424ms","start":"2026-06-17T15:28:12.159915Z","end":"2026-06-17T15:28:12.374022Z","steps":["trace[245648871] 'process raft request' (duration: 213.418718ms)"],"step_count":1}