{"level":"warn","ts":"2026-06-08T22:15:46.59116Z","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-08T22:15:46.591243Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-08T22:15:46.591319Z","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-08T22:15:46.591333Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-06-08T22:15:46.591353Z","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-08T22:15:46.591961Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-06-08T22:15:46.592032Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-08T22:15:46.597485Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.230055ms"} {"level":"info","ts":"2026-06-08T22:15:46.603881Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-06-08T22:15:46.603973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-06-08T22:15:46.604025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-06-08T22:15:46.604038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-08T22:15:46.60405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-06-08T22:15:46.604106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-06-08T22:15:46.609812Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-08T22:15:46.611194Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-08T22:15:46.612199Z","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-08T22:15:46.614006Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-08T22:15:46.61412Z","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-08T22:15:46.614599Z","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-08T22:15:46.614559Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-08T22:15:46.61462Z","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-08T22:15:46.615753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-06-08T22:15:46.615904Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-06-08T22:15:46.617447Z","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-08T22:15:46.617485Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-06-08T22:15:46.617593Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-06-08T22:15:46.617683Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-08T22:15:46.617715Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-08T22:15:47.604726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-06-08T22:15:47.605055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-06-08T22:15:47.605273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-06-08T22:15:47.605462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-06-08T22:15:47.605635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-06-08T22:15:47.60581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-06-08T22:15:47.605967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-06-08T22:15:47.607034Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:15:47.607831Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T22:15:47.607833Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-06-08T22:15:47.608059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T22:15:47.608402Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-08T22:15:47.608426Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-08T22:15:47.608257Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:15:47.608812Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:15:47.608883Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T22:15:47.61143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-08T22:15:47.614824Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-06-08T22:15:49.035324Z","caller":"traceutil/trace.go:171","msg":"trace[1778900119] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"113.900319ms","start":"2026-06-08T22:15:48.921396Z","end":"2026-06-08T22:15:49.035296Z","steps":["trace[1778900119] 'process raft request' (duration: 113.745764ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:49.035499Z","caller":"traceutil/trace.go:171","msg":"trace[351165815] linearizableReadLoop","detail":"{readStateIndex:36; appliedIndex:31; }","duration":"113.613351ms","start":"2026-06-08T22:15:48.921861Z","end":"2026-06-08T22:15:49.035474Z","steps":["trace[351165815] 'read index received' (duration: 58.467417ms)","trace[351165815] 'applied index is now lower than readState.Index' (duration: 55.144774ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:15:49.03552Z","caller":"traceutil/trace.go:171","msg":"trace[340595233] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"114.005492ms","start":"2026-06-08T22:15:48.921478Z","end":"2026-06-08T22:15:49.035483Z","steps":["trace[340595233] 'process raft request' (duration: 113.768135ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:49.03566Z","caller":"traceutil/trace.go:171","msg":"trace[2110356264] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"114.083724ms","start":"2026-06-08T22:15:48.921514Z","end":"2026-06-08T22:15:49.035598Z","steps":["trace[2110356264] 'process raft request' (duration: 113.762905ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:49.035698Z","caller":"traceutil/trace.go:171","msg":"trace[316631896] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"113.472208ms","start":"2026-06-08T22:15:48.922171Z","end":"2026-06-08T22:15:49.035643Z","steps":["trace[316631896] 'process raft request' (duration: 113.154249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:49.035922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.035123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:15:49.035988Z","caller":"traceutil/trace.go:171","msg":"trace[1615474103] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:35; }","duration":"114.145576ms","start":"2026-06-08T22:15:48.921827Z","end":"2026-06-08T22:15:49.035973Z","steps":["trace[1615474103] 'agreement among raft nodes before linearized reading' (duration: 113.94694ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:15:49.036271Z","caller":"traceutil/trace.go:171","msg":"trace[1892581446] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"113.93065ms","start":"2026-06-08T22:15:48.92228Z","end":"2026-06-08T22:15:49.036211Z","steps":["trace[1892581446] 'process raft request' (duration: 113.072886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:15:49.048709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.09326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-08T22:15:49.048789Z","caller":"traceutil/trace.go:171","msg":"trace[882238642] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:36; }","duration":"116.232125ms","start":"2026-06-08T22:15:48.932536Z","end":"2026-06-08T22:15:49.048769Z","steps":["trace[882238642] 'agreement among raft nodes before linearized reading' (duration: 115.842014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:03.819049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.352722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-08T22:16:03.819152Z","caller":"traceutil/trace.go:171","msg":"trace[211354808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"265.505298ms","start":"2026-06-08T22:16:03.553628Z","end":"2026-06-08T22:16:03.819134Z","steps":["trace[211354808] 'range keys from in-memory index tree' (duration: 265.23822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:03.819347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.291781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-06-08T22:16:03.819462Z","caller":"traceutil/trace.go:171","msg":"trace[200105713] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:372; }","duration":"224.433914ms","start":"2026-06-08T22:16:03.595Z","end":"2026-06-08T22:16:03.819433Z","steps":["trace[200105713] 'range keys from in-memory index tree' (duration: 224.172557ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:04.034577Z","caller":"traceutil/trace.go:171","msg":"trace[2103415876] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"207.057162ms","start":"2026-06-08T22:16:03.827487Z","end":"2026-06-08T22:16:04.034544Z","steps":["trace[2103415876] 'process raft request' (duration: 206.705452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:05.163004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.819809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:16:05.163097Z","caller":"traceutil/trace.go:171","msg":"trace[1727396541] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:384; }","duration":"1.17134212s","start":"2026-06-08T22:16:03.991739Z","end":"2026-06-08T22:16:05.163081Z","steps":["trace[1727396541] 'read index received' (duration: 42.443511ms)","trace[1727396541] 'applied index is now lower than readState.Index' (duration: 1.128897299s)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:05.163159Z","caller":"traceutil/trace.go:171","msg":"trace[1077927124] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"1.332932116s","start":"2026-06-08T22:16:03.830178Z","end":"2026-06-08T22:16:05.16311Z","steps":["trace[1077927124] 'process raft request' (duration: 1.095501991s)","trace[1077927124] 'compare' (duration: 235.861012ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:16:05.163267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019904727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-08T22:16:05.16327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154055609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:16:05.163298Z","caller":"traceutil/trace.go:171","msg":"trace[134838455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:374; }","duration":"1.019969659s","start":"2026-06-08T22:16:04.143318Z","end":"2026-06-08T22:16:05.163288Z","steps":["trace[134838455] 'agreement among raft nodes before linearized reading' (duration: 1.019928188s)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:05.163339Z","caller":"traceutil/trace.go:171","msg":"trace[1387384667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"1.154163592s","start":"2026-06-08T22:16:04.009154Z","end":"2026-06-08T22:16:05.163318Z","steps":["trace[1387384667] 'agreement among raft nodes before linearized reading' (duration: 1.154051659s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:05.163317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.692599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-08T22:16:05.163333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:04.143297Z","time spent":"1.020028979s","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-08T22:16:05.163389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:04.009129Z","time spent":"1.154251924s","remote":"127.0.0.1:53768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T22:16:05.163393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:03.830157Z","time spent":"1.333101451s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:16:05.163426Z","caller":"traceutil/trace.go:171","msg":"trace[1794439410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:374; }","duration":"152.826792ms","start":"2026-06-08T22:16:05.010562Z","end":"2026-06-08T22:16:05.163389Z","steps":["trace[1794439410] 'agreement among raft nodes before linearized reading' (duration: 152.680659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:05.163216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171483604s","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-08T22:16:05.163669Z","caller":"traceutil/trace.go:171","msg":"trace[1695291373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:374; }","duration":"1.171973997s","start":"2026-06-08T22:16:03.99168Z","end":"2026-06-08T22:16:05.163654Z","steps":["trace[1695291373] 'agreement among raft nodes before linearized reading' (duration: 1.171492734s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:05.163705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:03.991658Z","time spent":"1.172035539s","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-08T22:16:05.454509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.300586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:16:05.454773Z","caller":"traceutil/trace.go:171","msg":"trace[625556645] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:386; }","duration":"284.126705ms","start":"2026-06-08T22:16:05.170627Z","end":"2026-06-08T22:16:05.454754Z","steps":["trace[625556645] 'read index received' (duration: 23.868144ms)","trace[625556645] 'applied index is now lower than readState.Index' (duration: 260.257251ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:05.454765Z","caller":"traceutil/trace.go:171","msg":"trace[469949310] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"285.714689ms","start":"2026-06-08T22:16:05.169015Z","end":"2026-06-08T22:16:05.45473Z","steps":["trace[469949310] 'process raft request' (duration: 137.097104ms)","trace[469949310] 'compare' (duration: 148.177773ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:05.454827Z","caller":"traceutil/trace.go:171","msg":"trace[717860636] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"285.697459ms","start":"2026-06-08T22:16:05.169098Z","end":"2026-06-08T22:16:05.454796Z","steps":["trace[717860636] 'process raft request' (duration: 285.527404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:05.454958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.638255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-06-08T22:16:05.454944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.29019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:16:05.454997Z","caller":"traceutil/trace.go:171","msg":"trace[508091964] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:377; }","duration":"111.713739ms","start":"2026-06-08T22:16:05.343274Z","end":"2026-06-08T22:16:05.454988Z","steps":["trace[508091964] 'agreement among raft nodes before linearized reading' (duration: 111.593505ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:05.455021Z","caller":"traceutil/trace.go:171","msg":"trace[691491654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"284.418343ms","start":"2026-06-08T22:16:05.170582Z","end":"2026-06-08T22:16:05.455Z","steps":["trace[691491654] 'agreement among raft nodes before linearized reading' (duration: 284.257848ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:08.329022Z","caller":"traceutil/trace.go:171","msg":"trace[2001860888] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"238.357332ms","start":"2026-06-08T22:16:08.09064Z","end":"2026-06-08T22:16:08.328997Z","steps":["trace[2001860888] 'process raft request' (duration: 238.160136ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:12.582483Z","caller":"traceutil/trace.go:171","msg":"trace[2074987944] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"160.640458ms","start":"2026-06-08T22:16:12.421794Z","end":"2026-06-08T22:16:12.582435Z","steps":["trace[2074987944] 'process raft request' (duration: 88.503692ms)","trace[2074987944] 'compare' (duration: 71.864479ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:12.74815Z","caller":"traceutil/trace.go:171","msg":"trace[696155515] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"127.132258ms","start":"2026-06-08T22:16:12.620977Z","end":"2026-06-08T22:16:12.74811Z","steps":["trace[696155515] 'process raft request' (duration: 77.619ms)","trace[696155515] 'compare' (duration: 48.972873ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:12.748643Z","caller":"traceutil/trace.go:171","msg":"trace[1974283340] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"127.437836ms","start":"2026-06-08T22:16:12.621181Z","end":"2026-06-08T22:16:12.748619Z","steps":["trace[1974283340] 'process raft request' (duration: 126.724386ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:26.590388Z","caller":"traceutil/trace.go:171","msg":"trace[1318352052] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"161.102012ms","start":"2026-06-08T22:16:26.429269Z","end":"2026-06-08T22:16:26.590371Z","steps":["trace[1318352052] 'process raft request' (duration: 161.008689ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:26.68891Z","caller":"traceutil/trace.go:171","msg":"trace[914583981] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"104.997681ms","start":"2026-06-08T22:16:26.583883Z","end":"2026-06-08T22:16:26.688881Z","steps":["trace[914583981] 'process raft request' (duration: 104.759004ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:26.689126Z","caller":"traceutil/trace.go:171","msg":"trace[1653181675] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"105.011562ms","start":"2026-06-08T22:16:26.58402Z","end":"2026-06-08T22:16:26.689031Z","steps":["trace[1653181675] 'process raft request' (duration: 104.746005ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:26.689366Z","caller":"traceutil/trace.go:171","msg":"trace[1651447845] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"105.29139ms","start":"2026-06-08T22:16:26.584057Z","end":"2026-06-08T22:16:26.689348Z","steps":["trace[1651447845] 'process raft request' (duration: 104.735824ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:26.689378Z","caller":"traceutil/trace.go:171","msg":"trace[1453946335] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"100.803985ms","start":"2026-06-08T22:16:26.588531Z","end":"2026-06-08T22:16:26.689335Z","steps":["trace[1453946335] 'process raft request' (duration: 100.765594ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:26.695766Z","caller":"traceutil/trace.go:171","msg":"trace[5489862] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"103.726465ms","start":"2026-06-08T22:16:26.592024Z","end":"2026-06-08T22:16:26.69575Z","steps":["trace[5489862] 'process raft request' (duration: 103.464478ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:26.696249Z","caller":"traceutil/trace.go:171","msg":"trace[2104104319] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"102.137711ms","start":"2026-06-08T22:16:26.594102Z","end":"2026-06-08T22:16:26.696239Z","steps":["trace[2104104319] 'process raft request' (duration: 101.564056ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:26.696871Z","caller":"traceutil/trace.go:171","msg":"trace[581890242] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"104.347093ms","start":"2026-06-08T22:16:26.592518Z","end":"2026-06-08T22:16:26.696865Z","steps":["trace[581890242] 'process raft request' (duration: 103.075317ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:30.550025Z","caller":"traceutil/trace.go:171","msg":"trace[1630315329] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"202.180895ms","start":"2026-06-08T22:16:30.347815Z","end":"2026-06-08T22:16:30.549996Z","steps":["trace[1630315329] 'process raft request' (duration: 201.418353ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:30.75447Z","caller":"traceutil/trace.go:171","msg":"trace[364104735] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"147.178534ms","start":"2026-06-08T22:16:30.607258Z","end":"2026-06-08T22:16:30.754437Z","steps":["trace[364104735] 'process raft request' (duration: 129.928735ms)","trace[364104735] 'compare' (duration: 16.91473ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:30.7546Z","caller":"traceutil/trace.go:171","msg":"trace[1795340556] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"131.428998ms","start":"2026-06-08T22:16:30.623137Z","end":"2026-06-08T22:16:30.754566Z","steps":["trace[1795340556] 'process raft request' (duration: 131.206862ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:33.962584Z","caller":"traceutil/trace.go:171","msg":"trace[265115054] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"205.913389ms","start":"2026-06-08T22:16:33.756645Z","end":"2026-06-08T22:16:33.962558Z","steps":["trace[265115054] 'read index received' (duration: 205.137987ms)","trace[265115054] 'applied index is now lower than readState.Index' (duration: 774.462µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:33.962686Z","caller":"traceutil/trace.go:171","msg":"trace[1282494493] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"367.168035ms","start":"2026-06-08T22:16:33.595477Z","end":"2026-06-08T22:16:33.962645Z","steps":["trace[1282494493] 'process raft request' (duration: 366.487527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:33.962821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.298734ms","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-08T22:16:33.962867Z","caller":"traceutil/trace.go:171","msg":"trace[1943108201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"204.376406ms","start":"2026-06-08T22:16:33.758478Z","end":"2026-06-08T22:16:33.962854Z","steps":["trace[1943108201] 'agreement among raft nodes before linearized reading' (duration: 204.227082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:33.962883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:33.595448Z","time spent":"367.329069ms","remote":"127.0.0.1:53854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:16:33.962876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.212498ms","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-08T22:16:33.962974Z","caller":"traceutil/trace.go:171","msg":"trace[632464603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"206.361791ms","start":"2026-06-08T22:16:33.756589Z","end":"2026-06-08T22:16:33.96295Z","steps":["trace[632464603] 'agreement among raft nodes before linearized reading' (duration: 206.073503ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:34.339533Z","caller":"traceutil/trace.go:171","msg":"trace[1359795036] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"373.025278ms","start":"2026-06-08T22:16:33.966474Z","end":"2026-06-08T22:16:34.339499Z","steps":["trace[1359795036] 'process raft request' (duration: 302.882587ms)","trace[1359795036] 'compare' (duration: 70.003988ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:16:34.339697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:33.966458Z","time spent":"373.158842ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:16:34.535951Z","caller":"traceutil/trace.go:171","msg":"trace[32930451] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"531.218699ms","start":"2026-06-08T22:16:34.004705Z","end":"2026-06-08T22:16:34.535924Z","steps":["trace[32930451] 'read index received' (duration: 264.663973ms)","trace[32930451] 'applied index is now lower than readState.Index' (duration: 266.553426ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:34.535995Z","caller":"traceutil/trace.go:171","msg":"trace[205153597] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"568.987662ms","start":"2026-06-08T22:16:33.966974Z","end":"2026-06-08T22:16:34.535962Z","steps":["trace[205153597] 'process raft request' (duration: 568.766546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:34.536257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.521138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:16:34.53638Z","caller":"traceutil/trace.go:171","msg":"trace[1523145244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"531.681003ms","start":"2026-06-08T22:16:34.004669Z","end":"2026-06-08T22:16:34.53635Z","steps":["trace[1523145244] 'agreement among raft nodes before linearized reading' (duration: 531.447707ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:34.536252Z","caller":"traceutil/trace.go:171","msg":"trace[292337879] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"503.202601ms","start":"2026-06-08T22:16:34.03299Z","end":"2026-06-08T22:16:34.536193Z","steps":["trace[292337879] 'process raft request' (duration: 502.891522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:34.536439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:34.004654Z","time spent":"531.776496ms","remote":"127.0.0.1:53768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T22:16:34.536588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:34.032964Z","time spent":"503.52563ms","remote":"127.0.0.1:53994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:16:34.536591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.355519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rmr8s\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-06-08T22:16:34.536751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:33.966954Z","time spent":"569.558127ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:16:34.536776Z","caller":"traceutil/trace.go:171","msg":"trace[1578590434] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rmr8s; range_end:; response_count:1; response_revision:603; }","duration":"239.450652ms","start":"2026-06-08T22:16:34.297195Z","end":"2026-06-08T22:16:34.536646Z","steps":["trace[1578590434] 'agreement among raft nodes before linearized reading' (duration: 239.153033ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:35.182807Z","caller":"traceutil/trace.go:171","msg":"trace[409424966] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"560.599218ms","start":"2026-06-08T22:16:34.622175Z","end":"2026-06-08T22:16:35.182774Z","steps":["trace[409424966] 'process raft request' (duration: 560.452173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:35.182957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:34.622147Z","time spent":"560.73008ms","remote":"127.0.0.1:53994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:16:35.517141Z","caller":"traceutil/trace.go:171","msg":"trace[1714459711] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:632; }","duration":"733.153187ms","start":"2026-06-08T22:16:34.783969Z","end":"2026-06-08T22:16:35.517122Z","steps":["trace[1714459711] 'read index received' (duration: 398.796065ms)","trace[1714459711] 'applied index is now lower than readState.Index' (duration: 334.355902ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:35.517125Z","caller":"traceutil/trace.go:171","msg":"trace[1467414071] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"894.885377ms","start":"2026-06-08T22:16:34.622216Z","end":"2026-06-08T22:16:35.517102Z","steps":["trace[1467414071] 'process raft request' (duration: 858.564066ms)","trace[1467414071] 'compare' (duration: 35.730415ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:16:35.517285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:34.622181Z","time spent":"895.055702ms","remote":"127.0.0.1:53836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T22:16:35.517278Z","caller":"traceutil/trace.go:171","msg":"trace[1039246183] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"894.914679ms","start":"2026-06-08T22:16:34.622351Z","end":"2026-06-08T22:16:35.517266Z","steps":["trace[1039246183] 'process raft request' (duration: 894.636951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:35.517464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:34.622307Z","time spent":"895.074333ms","remote":"127.0.0.1:53836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T22:16:35.517768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.809106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-08T22:16:35.517797Z","caller":"traceutil/trace.go:171","msg":"trace[1278703433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"733.887778ms","start":"2026-06-08T22:16:34.783901Z","end":"2026-06-08T22:16:35.517789Z","steps":["trace[1278703433] 'agreement among raft nodes before linearized reading' (duration: 733.5968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:35.517815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:34.783879Z","time spent":"733.93107ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-08T22:16:35.517859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.259281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:16:35.517944Z","caller":"traceutil/trace.go:171","msg":"trace[1977604129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"508.407935ms","start":"2026-06-08T22:16:35.009516Z","end":"2026-06-08T22:16:35.517924Z","steps":["trace[1977604129] 'agreement among raft nodes before linearized reading' (duration: 508.24338ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:35.517925Z","caller":"traceutil/trace.go:171","msg":"trace[225191371] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"894.124367ms","start":"2026-06-08T22:16:34.623781Z","end":"2026-06-08T22:16:35.517906Z","steps":["trace[225191371] 'process raft request' (duration: 893.263523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:35.517996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:35.009493Z","time spent":"508.495877ms","remote":"127.0.0.1:53758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T22:16:35.517998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.161041ms","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-08T22:16:35.518069Z","caller":"traceutil/trace.go:171","msg":"trace[1948718084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"135.286544ms","start":"2026-06-08T22:16:35.382764Z","end":"2026-06-08T22:16:35.518051Z","steps":["trace[1948718084] 'agreement among raft nodes before linearized reading' (duration: 135.055837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:35.518138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:34.623755Z","time spent":"894.256831ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:16:46.06202Z","caller":"traceutil/trace.go:171","msg":"trace[1273279706] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"111.411244ms","start":"2026-06-08T22:16:45.950581Z","end":"2026-06-08T22:16:46.061993Z","steps":["trace[1273279706] 'process raft request' (duration: 91.699821ms)","trace[1273279706] 'compare' (duration: 19.549148ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:16:51.158774Z","caller":"traceutil/trace.go:171","msg":"trace[1305377966] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"480.430544ms","start":"2026-06-08T22:16:50.678324Z","end":"2026-06-08T22:16:51.158755Z","steps":["trace[1305377966] 'read index received' (duration: 480.263009ms)","trace[1305377966] 'applied index is now lower than readState.Index' (duration: 166.685µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:16:51.158827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:50.607Z","time spent":"551.817994ms","remote":"127.0.0.1:53780","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-08T22:16:51.158939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.596949ms","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-08T22:16:51.158963Z","caller":"traceutil/trace.go:171","msg":"trace[1804007834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"480.64019ms","start":"2026-06-08T22:16:50.678316Z","end":"2026-06-08T22:16:51.158957Z","steps":["trace[1804007834] 'agreement among raft nodes before linearized reading' (duration: 480.524107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:51.158993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:16:50.678264Z","time spent":"480.724023ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-08T22:16:51.15911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.105533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-08T22:16:51.159196Z","caller":"traceutil/trace.go:171","msg":"trace[1064135048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"274.240285ms","start":"2026-06-08T22:16:50.884938Z","end":"2026-06-08T22:16:51.159178Z","steps":["trace[1064135048] 'agreement among raft nodes before linearized reading' (duration: 273.998949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:16:51.159107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.133213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:16:51.15929Z","caller":"traceutil/trace.go:171","msg":"trace[848279777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"150.368999ms","start":"2026-06-08T22:16:51.008905Z","end":"2026-06-08T22:16:51.159274Z","steps":["trace[848279777] 'agreement among raft nodes before linearized reading' (duration: 150.085602ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:51.287122Z","caller":"traceutil/trace.go:171","msg":"trace[304641291] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"125.904904ms","start":"2026-06-08T22:16:51.161192Z","end":"2026-06-08T22:16:51.287097Z","steps":["trace[304641291] 'process raft request' (duration: 120.189126ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:51.287307Z","caller":"traceutil/trace.go:171","msg":"trace[73315983] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"123.711673ms","start":"2026-06-08T22:16:51.163574Z","end":"2026-06-08T22:16:51.287286Z","steps":["trace[73315983] 'process raft request' (duration: 123.555119ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:16:51.287491Z","caller":"traceutil/trace.go:171","msg":"trace[916098463] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"122.962842ms","start":"2026-06-08T22:16:51.164508Z","end":"2026-06-08T22:16:51.287471Z","steps":["trace[916098463] 'process raft request' (duration: 122.744146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:26.11276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.676319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:17:26.112887Z","caller":"traceutil/trace.go:171","msg":"trace[1230906724] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"620.814199ms","start":"2026-06-08T22:17:25.492054Z","end":"2026-06-08T22:17:26.112868Z","steps":["trace[1230906724] 'process raft request' (duration: 275.953854ms)","trace[1230906724] 'compare' (duration: 344.587767ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:26.11295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:25.492033Z","time spent":"620.89384ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:17:26.308311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.450282ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-08T22:17:26.308392Z","caller":"traceutil/trace.go:171","msg":"trace[1373258097] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1017; }","duration":"500.93719ms","start":"2026-06-08T22:17:25.807442Z","end":"2026-06-08T22:17:26.308379Z","steps":["trace[1373258097] 'read index received' (duration: 235.826237ms)","trace[1373258097] 'applied index is now lower than readState.Index' (duration: 265.110113ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:26.308564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.243695ms","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-08T22:17:26.308592Z","caller":"traceutil/trace.go:171","msg":"trace[1989158170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"432.314597ms","start":"2026-06-08T22:17:25.876269Z","end":"2026-06-08T22:17:26.308583Z","steps":["trace[1989158170] 'agreement among raft nodes before linearized reading' (duration: 432.193964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:26.308567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.130205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-08T22:17:26.308572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.986608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T22:17:26.308621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:25.876246Z","time spent":"432.369228ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-08T22:17:26.308637Z","caller":"traceutil/trace.go:171","msg":"trace[1251040668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"501.200707ms","start":"2026-06-08T22:17:25.807416Z","end":"2026-06-08T22:17:26.308617Z","steps":["trace[1251040668] 'agreement among raft nodes before linearized reading' (duration: 501.019952ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:26.308647Z","caller":"traceutil/trace.go:171","msg":"trace[696045470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"299.552315ms","start":"2026-06-08T22:17:26.009076Z","end":"2026-06-08T22:17:26.308628Z","steps":["trace[696045470] 'agreement among raft nodes before linearized reading' (duration: 299.42886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:26.308679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:25.807357Z","time spent":"501.31277ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-08T22:17:47.691311Z","caller":"traceutil/trace.go:171","msg":"trace[1435650298] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"123.354312ms","start":"2026-06-08T22:17:47.567935Z","end":"2026-06-08T22:17:47.69129Z","steps":["trace[1435650298] 'process raft request' (duration: 123.219849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:49.022274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.000278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T22:17:49.0226Z","caller":"traceutil/trace.go:171","msg":"trace[1202930558] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"281.506737ms","start":"2026-06-08T22:17:48.741052Z","end":"2026-06-08T22:17:49.022559Z","steps":["trace[1202930558] 'process raft request' (duration: 281.322391ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:49.02262Z","caller":"traceutil/trace.go:171","msg":"trace[2031439881] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"439.795604ms","start":"2026-06-08T22:17:48.582764Z","end":"2026-06-08T22:17:49.02256Z","steps":["trace[2031439881] 'process raft request' (duration: 160.388505ms)","trace[2031439881] 'compare' (duration: 278.866874ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:17:49.022794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:17:48.582739Z","time spent":"439.962748ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:17:49.948801Z","caller":"traceutil/trace.go:171","msg":"trace[1395108255] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"247.697463ms","start":"2026-06-08T22:17:49.701078Z","end":"2026-06-08T22:17:49.948775Z","steps":["trace[1395108255] 'process raft request' (duration: 247.5595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:17:50.337134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.516176ms","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-08T22:17:50.337224Z","caller":"traceutil/trace.go:171","msg":"trace[1885283858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"100.650139ms","start":"2026-06-08T22:17:50.236552Z","end":"2026-06-08T22:17:50.337202Z","steps":["trace[1885283858] 'range keys from in-memory index tree' (duration: 100.30964ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:17:50.470776Z","caller":"traceutil/trace.go:171","msg":"trace[1255773637] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"130.451659ms","start":"2026-06-08T22:17:50.340298Z","end":"2026-06-08T22:17:50.47075Z","steps":["trace[1255773637] 'process raft request' (duration: 130.247593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:07.62888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938292044977714701,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-08T22:18:07.651383Z","caller":"traceutil/trace.go:171","msg":"trace[1046945992] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"522.734735ms","start":"2026-06-08T22:18:07.128622Z","end":"2026-06-08T22:18:07.651357Z","steps":["trace[1046945992] 'read index received' (duration: 522.480408ms)","trace[1046945992] 'applied index is now lower than readState.Index' (duration: 253.227µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:18:07.651546Z","caller":"traceutil/trace.go:171","msg":"trace[28142575] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"541.869913ms","start":"2026-06-08T22:18:07.109628Z","end":"2026-06-08T22:18:07.651498Z","steps":["trace[28142575] 'process raft request' (duration: 541.554323ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:07.651643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.002432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-08T22:18:07.651703Z","caller":"traceutil/trace.go:171","msg":"trace[1124956873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1164; }","duration":"523.095425ms","start":"2026-06-08T22:18:07.128585Z","end":"2026-06-08T22:18:07.65168Z","steps":["trace[1124956873] 'agreement among raft nodes before linearized reading' (duration: 522.91704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:07.651711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:07.109597Z","time spent":"542.013387ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:18:07.651724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.724612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-08T22:18:07.651746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:07.128569Z","time spent":"523.165557ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-08T22:18:07.651802Z","caller":"traceutil/trace.go:171","msg":"trace[584039192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1164; }","duration":"513.847834ms","start":"2026-06-08T22:18:07.137936Z","end":"2026-06-08T22:18:07.651784Z","steps":["trace[584039192] 'agreement among raft nodes before linearized reading' (duration: 513.609398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:07.651853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:07.137919Z","time spent":"513.920717ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-08T22:18:15.797025Z","caller":"traceutil/trace.go:171","msg":"trace[1496402713] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"106.886721ms","start":"2026-06-08T22:18:15.690105Z","end":"2026-06-08T22:18:15.796992Z","steps":["trace[1496402713] 'process raft request' (duration: 105.665175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:24.667101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.821033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:18:24.667167Z","caller":"traceutil/trace.go:171","msg":"trace[1533900718] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1241; }","duration":"152.957076ms","start":"2026-06-08T22:18:24.514195Z","end":"2026-06-08T22:18:24.667152Z","steps":["trace[1533900718] 'count revisions from in-memory index tree' (duration: 152.73715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:24.943843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.843641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-08T22:18:24.943931Z","caller":"traceutil/trace.go:171","msg":"trace[1617128060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"179.999947ms","start":"2026-06-08T22:18:24.763912Z","end":"2026-06-08T22:18:24.943912Z","steps":["trace[1617128060] 'agreement among raft nodes before linearized reading' (duration: 179.720239ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:24.944048Z","caller":"traceutil/trace.go:171","msg":"trace[1219971519] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"179.521632ms","start":"2026-06-08T22:18:24.763944Z","end":"2026-06-08T22:18:24.943465Z","steps":["trace[1219971519] 'read index received' (duration: 179.278855ms)","trace[1219971519] 'applied index is now lower than readState.Index' (duration: 242.037µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:18:24.944228Z","caller":"traceutil/trace.go:171","msg":"trace[1375327919] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"272.28215ms","start":"2026-06-08T22:18:24.671286Z","end":"2026-06-08T22:18:24.943568Z","steps":["trace[1375327919] 'process raft request' (duration: 272.035584ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:25.274509Z","caller":"traceutil/trace.go:171","msg":"trace[1726548958] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"326.67874ms","start":"2026-06-08T22:18:24.947765Z","end":"2026-06-08T22:18:25.274444Z","steps":["trace[1726548958] 'process raft request' (duration: 234.023884ms)","trace[1726548958] 'compare' (duration: 92.352437ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:18:25.27467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.615058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:18:25.274649Z","caller":"traceutil/trace.go:171","msg":"trace[1129537865] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"264.249496ms","start":"2026-06-08T22:18:25.010027Z","end":"2026-06-08T22:18:25.274276Z","steps":["trace[1129537865] 'read index received' (duration: 171.768346ms)","trace[1129537865] 'applied index is now lower than readState.Index' (duration: 92.48016ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:18:25.274755Z","caller":"traceutil/trace.go:171","msg":"trace[1006901441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"264.768042ms","start":"2026-06-08T22:18:25.009968Z","end":"2026-06-08T22:18:25.274736Z","steps":["trace[1006901441] 'agreement among raft nodes before linearized reading' (duration: 264.580836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:25.274793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:24.947744Z","time spent":"326.904336ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:18:25.27489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.895204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T22:18:25.274941Z","caller":"traceutil/trace.go:171","msg":"trace[1067007238] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1243; }","duration":"117.011657ms","start":"2026-06-08T22:18:25.157913Z","end":"2026-06-08T22:18:25.274925Z","steps":["trace[1067007238] 'agreement among raft nodes before linearized reading' (duration: 116.890623ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:25.886619Z","caller":"traceutil/trace.go:171","msg":"trace[463814458] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"153.72121ms","start":"2026-06-08T22:18:25.732864Z","end":"2026-06-08T22:18:25.886585Z","steps":["trace[463814458] 'process raft request' (duration: 153.489743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:26.132559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.780788ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-08T22:18:26.132689Z","caller":"traceutil/trace.go:171","msg":"trace[914092386] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1303; }","duration":"302.249897ms","start":"2026-06-08T22:18:25.830421Z","end":"2026-06-08T22:18:26.132671Z","steps":["trace[914092386] 'read index received' (duration: 55.955035ms)","trace[914092386] 'applied index is now lower than readState.Index' (duration: 246.293232ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T22:18:26.132984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.926566ms","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-08T22:18:26.132985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.532394ms","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-08T22:18:26.133015Z","caller":"traceutil/trace.go:171","msg":"trace[1553287288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1252; }","duration":"278.991147ms","start":"2026-06-08T22:18:25.854014Z","end":"2026-06-08T22:18:26.133005Z","steps":["trace[1553287288] 'agreement among raft nodes before linearized reading' (duration: 278.883844ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:26.13302Z","caller":"traceutil/trace.go:171","msg":"trace[568151482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1252; }","duration":"302.611986ms","start":"2026-06-08T22:18:25.830398Z","end":"2026-06-08T22:18:26.13301Z","steps":["trace[568151482] 'agreement among raft nodes before linearized reading' (duration: 302.466112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:26.132986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.935359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T22:18:26.133042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:25.830391Z","time spent":"302.645417ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-08T22:18:26.133084Z","caller":"traceutil/trace.go:171","msg":"trace[10801112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1252; }","duration":"123.068993ms","start":"2026-06-08T22:18:26.009976Z","end":"2026-06-08T22:18:26.133045Z","steps":["trace[10801112] 'agreement among raft nodes before linearized reading' (duration: 122.937069ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:26.274597Z","caller":"traceutil/trace.go:171","msg":"trace[12637957] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"136.435425ms","start":"2026-06-08T22:18:26.138139Z","end":"2026-06-08T22:18:26.274574Z","steps":["trace[12637957] 'process raft request' (duration: 128.229275ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:26.474912Z","caller":"traceutil/trace.go:171","msg":"trace[379458613] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"168.361397ms","start":"2026-06-08T22:18:26.306527Z","end":"2026-06-08T22:18:26.474888Z","steps":["trace[379458613] 'process raft request' (duration: 168.181492ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:35.626522Z","caller":"traceutil/trace.go:171","msg":"trace[1621839428] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"393.44088ms","start":"2026-06-08T22:18:35.233057Z","end":"2026-06-08T22:18:35.626498Z","steps":["trace[1621839428] 'read index received' (duration: 393.186362ms)","trace[1621839428] 'applied index is now lower than readState.Index' (duration: 253.228µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:18:35.626589Z","caller":"traceutil/trace.go:171","msg":"trace[934828900] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"400.182576ms","start":"2026-06-08T22:18:35.226373Z","end":"2026-06-08T22:18:35.626555Z","steps":["trace[934828900] 'process raft request' (duration: 399.96414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:35.626724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.636565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:18:35.626778Z","caller":"traceutil/trace.go:171","msg":"trace[849131411] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1320; }","duration":"393.727568ms","start":"2026-06-08T22:18:35.233036Z","end":"2026-06-08T22:18:35.626763Z","steps":["trace[849131411] 'agreement among raft nodes before linearized reading' (duration: 393.600944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:35.626778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:35.226352Z","time spent":"400.32848ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:18:35.626823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:35.233027Z","time spent":"393.782429ms","remote":"127.0.0.1:42512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-06-08T22:18:35.626856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.534988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-08T22:18:35.626909Z","caller":"traceutil/trace.go:171","msg":"trace[2023324241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"311.615499ms","start":"2026-06-08T22:18:35.31528Z","end":"2026-06-08T22:18:35.626895Z","steps":["trace[2023324241] 'agreement among raft nodes before linearized reading' (duration: 311.465295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:35.626944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:35.315263Z","time spent":"311.671791ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-08T22:18:36.279926Z","caller":"traceutil/trace.go:171","msg":"trace[2097931170] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"648.871128ms","start":"2026-06-08T22:18:35.631028Z","end":"2026-06-08T22:18:36.279899Z","steps":["trace[2097931170] 'process raft request' (duration: 648.705613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:36.280072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:35.631004Z","time spent":"649.002342ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:18:36.584038Z","caller":"traceutil/trace.go:171","msg":"trace[2105233738] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"952.612818ms","start":"2026-06-08T22:18:35.631397Z","end":"2026-06-08T22:18:36.584009Z","steps":["trace[2105233738] 'process raft request' (duration: 949.798025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:36.5842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:35.631379Z","time spent":"952.732352ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:18:36.586099Z","caller":"traceutil/trace.go:171","msg":"trace[1478698678] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1375; }","duration":"576.193576ms","start":"2026-06-08T22:18:36.009879Z","end":"2026-06-08T22:18:36.586073Z","steps":["trace[1478698678] 'read index received' (duration: 269.949353ms)","trace[1478698678] 'applied index is now lower than readState.Index' (duration: 306.242923ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T22:18:36.586257Z","caller":"traceutil/trace.go:171","msg":"trace[1239977478] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"672.052274ms","start":"2026-06-08T22:18:35.914195Z","end":"2026-06-08T22:18:36.586247Z","steps":["trace[1239977478] 'process raft request' (duration: 671.728865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:36.58636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:35.914175Z","time spent":"672.109546ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T22:18:36.586493Z","caller":"traceutil/trace.go:171","msg":"trace[1132765719] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"643.208973ms","start":"2026-06-08T22:18:35.943275Z","end":"2026-06-08T22:18:36.586484Z","steps":["trace[1132765719] 'process raft request' (duration: 642.741969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:36.586537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:35.943247Z","time spent":"643.265754ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T22:18:36.586786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.611237ms","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-08T22:18:36.586796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.118077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:18:36.586833Z","caller":"traceutil/trace.go:171","msg":"trace[2011281965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"276.699611ms","start":"2026-06-08T22:18:36.310122Z","end":"2026-06-08T22:18:36.586822Z","steps":["trace[2011281965] 'agreement among raft nodes before linearized reading' (duration: 276.545896ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:36.586879Z","caller":"traceutil/trace.go:171","msg":"trace[82025649] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1324; }","duration":"572.285672ms","start":"2026-06-08T22:18:36.014552Z","end":"2026-06-08T22:18:36.586838Z","steps":["trace[82025649] 'agreement among raft nodes before linearized reading' (duration: 572.023924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:36.58698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:36.014511Z","time spent":"572.454736ms","remote":"127.0.0.1:39268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-06-08T22:18:36.587096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.217356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:18:36.587142Z","caller":"traceutil/trace.go:171","msg":"trace[23683194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"577.300859ms","start":"2026-06-08T22:18:36.00983Z","end":"2026-06-08T22:18:36.58713Z","steps":["trace[23683194] 'agreement among raft nodes before linearized reading' (duration: 577.225246ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:36.587171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:36.009807Z","time spent":"577.35774ms","remote":"127.0.0.1:53758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-08T22:18:36.587476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.003839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-06-08T22:18:36.587516Z","caller":"traceutil/trace.go:171","msg":"trace[1845230740] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1324; }","duration":"477.102562ms","start":"2026-06-08T22:18:36.110401Z","end":"2026-06-08T22:18:36.587504Z","steps":["trace[1845230740] 'agreement among raft nodes before linearized reading' (duration: 476.916507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:36.587576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:36.110381Z","time spent":"477.185245ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":546,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-08T22:18:36.587534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.365087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-08T22:18:36.587684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.852241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T22:18:36.587697Z","caller":"traceutil/trace.go:171","msg":"trace[171216241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"317.575404ms","start":"2026-06-08T22:18:36.270097Z","end":"2026-06-08T22:18:36.587673Z","steps":["trace[171216241] 'agreement among raft nodes before linearized reading' (duration: 317.217283ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T22:18:36.587721Z","caller":"traceutil/trace.go:171","msg":"trace[775924947] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1324; }","duration":"307.936421ms","start":"2026-06-08T22:18:36.279772Z","end":"2026-06-08T22:18:36.587709Z","steps":["trace[775924947] 'agreement among raft nodes before linearized reading' (duration: 307.873299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T22:18:36.587753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:36.279752Z","time spent":"307.991744ms","remote":"127.0.0.1:39198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-08T22:18:36.58779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T22:18:36.270078Z","time spent":"317.695967ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-08T22:22:28.307276Z","caller":"traceutil/trace.go:171","msg":"trace[1833164420] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"140.430128ms","start":"2026-06-08T22:22:28.166822Z","end":"2026-06-08T22:22:28.307252Z","steps":["trace[1833164420] 'process raft request' (duration: 140.285315ms)"],"step_count":1}