{"level":"warn","ts":"2026-08-14T04:29:06.560974Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T04:29:06.561109Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-08-14T04:29:06.561236Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T04:29:06.561262Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-08-14T04:29:06.561317Z","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-08-14T04:29:06.563644Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-08-14T04:29:06.563878Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-08-14T04:29:06.567947Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.670403ms"} {"level":"info","ts":"2026-08-14T04:29:06.572602Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-08-14T04:29:06.572652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T04:29:06.572672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-08-14T04:29:06.57268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T04:29:06.572688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-08-14T04:29:06.572718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-08-14T04:29:06.579305Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T04:29:06.580996Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T04:29:06.582054Z","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-08-14T04:29:06.584131Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T04:29:06.584354Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T04:29:06.584886Z","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-08-14T04:29:06.584979Z","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-08-14T04:29:06.585235Z","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-08-14T04:29:06.586525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-08-14T04:29:06.586641Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-08-14T04:29:06.588246Z","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-08-14T04:29:06.588303Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-08-14T04:29:06.588332Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-08-14T04:29:06.588447Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T04:29:06.588471Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T04:29:06.673386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T04:29:06.673473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T04:29:06.673525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-08-14T04:29:06.673556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-08-14T04:29:06.673619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-08-14T04:29:06.673643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-08-14T04:29:06.673733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-08-14T04:29:06.674984Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T04:29:06.675243Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T04:29:06.675503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T04:29:06.675534Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T04:29:06.675752Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T04:29:06.675828Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T04:29:06.675949Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T04:29:06.675848Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T04:29:06.675996Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T04:29:06.67952Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T04:29:06.680133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-08-14T04:29:08.811124Z","caller":"traceutil/trace.go:171","msg":"trace[2033767132] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"172.531485ms","start":"2026-08-14T04:29:08.638554Z","end":"2026-08-14T04:29:08.811085Z","steps":["trace[2033767132] 'read index received' (duration: 172.156784ms)","trace[2033767132] 'applied index is now lower than readState.Index' (duration: 373.081µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:29:08.811225Z","caller":"traceutil/trace.go:171","msg":"trace[110471090] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"172.69881ms","start":"2026-08-14T04:29:08.63849Z","end":"2026-08-14T04:29:08.811189Z","steps":["trace[110471090] 'process raft request' (duration: 172.159334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:08.811604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.98235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-14T04:29:08.811724Z","caller":"traceutil/trace.go:171","msg":"trace[742778111] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2; }","duration":"173.155585ms","start":"2026-08-14T04:29:08.638546Z","end":"2026-08-14T04:29:08.811701Z","steps":["trace[742778111] 'agreement among raft nodes before linearized reading' (duration: 172.795224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.51Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.930802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-08-14T04:29:09.510351Z","caller":"traceutil/trace.go:171","msg":"trace[1021777988] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"796.117511ms","start":"2026-08-14T04:29:08.714208Z","end":"2026-08-14T04:29:09.510326Z","steps":["trace[1021777988] 'process raft request' (duration: 795.958456ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:29:09.510358Z","caller":"traceutil/trace.go:171","msg":"trace[1635671313] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"871.066125ms","start":"2026-08-14T04:29:08.639208Z","end":"2026-08-14T04:29:09.510274Z","steps":["trace[1635671313] 'process raft request' (duration: 665.173534ms)","trace[1635671313] 'compare' (duration: 204.707395ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:29:09.510488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.714177Z","time spent":"796.252085ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T04:29:09.510596Z","caller":"traceutil/trace.go:171","msg":"trace[82758998] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"699.283092ms","start":"2026-08-14T04:29:08.811296Z","end":"2026-08-14T04:29:09.510579Z","steps":["trace[82758998] 'read index received' (duration: 493.075902ms)","trace[82758998] 'applied index is now lower than readState.Index' (duration: 206.20578ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:29:09.510679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.639201Z","time spent":"871.290253ms","remote":"127.0.0.1:52250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:29:09.510718Z","caller":"traceutil/trace.go:171","msg":"trace[438616499] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"796.332157ms","start":"2026-08-14T04:29:08.714356Z","end":"2026-08-14T04:29:09.510688Z","steps":["trace[438616499] 'process raft request' (duration: 795.891303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.510873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.40583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-14T04:29:09.5109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.714339Z","time spent":"796.482042ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T04:29:09.510913Z","caller":"traceutil/trace.go:171","msg":"trace[1548149938] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"744.480642ms","start":"2026-08-14T04:29:08.76642Z","end":"2026-08-14T04:29:09.510901Z","steps":["trace[1548149938] 'agreement among raft nodes before linearized reading' (duration: 744.297377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.510956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.766396Z","time spent":"744.552145ms","remote":"127.0.0.1:52294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-14T04:29:09.510691Z","caller":"traceutil/trace.go:171","msg":"trace[518809446] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"781.510412ms","start":"2026-08-14T04:29:08.729169Z","end":"2026-08-14T04:29:09.510679Z","steps":["trace[518809446] 'process raft request' (duration: 781.274774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.511074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.700628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-14T04:29:09.511077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.729143Z","time spent":"781.902954ms","remote":"127.0.0.1:52358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T04:29:09.511118Z","caller":"traceutil/trace.go:171","msg":"trace[2143759520] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"796.567284ms","start":"2026-08-14T04:29:08.714544Z","end":"2026-08-14T04:29:09.511111Z","steps":["trace[2143759520] 'process raft request' (duration: 795.78427ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:29:09.511124Z","caller":"traceutil/trace.go:171","msg":"trace[1499504383] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"796.788961ms","start":"2026-08-14T04:29:08.714309Z","end":"2026-08-14T04:29:09.511098Z","steps":["trace[1499504383] 'agreement among raft nodes before linearized reading' (duration: 796.724879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.511174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.71452Z","time spent":"796.621706ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T04:29:09.511178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.911251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-14T04:29:09.511197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.714299Z","time spent":"796.889414ms","remote":"127.0.0.1:52258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-08-14T04:29:09.511268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.481608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-08-14T04:29:09.511298Z","caller":"traceutil/trace.go:171","msg":"trace[913419402] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"872.51241ms","start":"2026-08-14T04:29:08.638776Z","end":"2026-08-14T04:29:09.511289Z","steps":["trace[913419402] 'agreement among raft nodes before linearized reading' (duration: 872.462109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.511011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.525608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-14T04:29:09.511332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.638698Z","time spent":"872.624033ms","remote":"127.0.0.1:52358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-14T04:29:09.511358Z","caller":"traceutil/trace.go:171","msg":"trace[1512943863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"870.90091ms","start":"2026-08-14T04:29:08.640438Z","end":"2026-08-14T04:29:09.511339Z","steps":["trace[1512943863] 'agreement among raft nodes before linearized reading' (duration: 870.521268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.511409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.640422Z","time spent":"870.971323ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T04:29:09.51148Z","caller":"traceutil/trace.go:171","msg":"trace[1999762073] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"796.383389ms","start":"2026-08-14T04:29:08.715078Z","end":"2026-08-14T04:29:09.511461Z","steps":["trace[1999762073] 'process raft request' (duration: 795.284565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.511513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.812257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-14T04:29:09.511084Z","caller":"traceutil/trace.go:171","msg":"trace[343790920] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"796.674738ms","start":"2026-08-14T04:29:08.714399Z","end":"2026-08-14T04:29:09.511073Z","steps":["trace[343790920] 'process raft request' (duration: 795.881013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.511535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.715067Z","time spent":"796.44483ms","remote":"127.0.0.1:52608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T04:29:09.51156Z","caller":"traceutil/trace.go:171","msg":"trace[1007332031] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"697.90506ms","start":"2026-08-14T04:29:08.813644Z","end":"2026-08-14T04:29:09.511549Z","steps":["trace[1007332031] 'agreement among raft nodes before linearized reading' (duration: 697.845668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.511594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.813626Z","time spent":"697.959792ms","remote":"127.0.0.1:52234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-08-14T04:29:09.511594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.714389Z","time spent":"797.170483ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T04:29:09.511662Z","caller":"traceutil/trace.go:171","msg":"trace[1787708618] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"796.40148ms","start":"2026-08-14T04:29:08.715252Z","end":"2026-08-14T04:29:09.511654Z","steps":["trace[1787708618] 'process raft request' (duration: 795.158361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.511709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.715235Z","time spent":"796.45012ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T04:29:09.5119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.91726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-gkxtl\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-08-14T04:29:09.511977Z","caller":"traceutil/trace.go:171","msg":"trace[157394507] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-gkxtl; range_end:; response_count:1; response_revision:10; }","duration":"696.995522ms","start":"2026-08-14T04:29:08.814932Z","end":"2026-08-14T04:29:09.511928Z","steps":["trace[157394507] 'agreement among raft nodes before linearized reading' (duration: 696.92704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.512017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.814918Z","time spent":"697.087375ms","remote":"127.0.0.1:52426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-gkxtl\" "} {"level":"info","ts":"2026-08-14T04:29:09.511216Z","caller":"traceutil/trace.go:171","msg":"trace[2013925031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"870.985273ms","start":"2026-08-14T04:29:08.640219Z","end":"2026-08-14T04:29:09.511204Z","steps":["trace[2013925031] 'agreement among raft nodes before linearized reading' (duration: 870.926521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.512345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.757661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-14T04:29:09.512357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.296688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-14T04:29:09.512373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.640203Z","time spent":"872.155709ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T04:29:09.512382Z","caller":"traceutil/trace.go:171","msg":"trace[1722757265] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"824.893296ms","start":"2026-08-14T04:29:08.687478Z","end":"2026-08-14T04:29:09.512372Z","steps":["trace[1722757265] 'agreement among raft nodes before linearized reading' (duration: 824.826464ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:29:09.512395Z","caller":"traceutil/trace.go:171","msg":"trace[741339307] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"799.427722ms","start":"2026-08-14T04:29:08.712956Z","end":"2026-08-14T04:29:09.512384Z","steps":["trace[741339307] 'agreement among raft nodes before linearized reading' (duration: 799.35682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.512425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.712935Z","time spent":"799.484004ms","remote":"127.0.0.1:52294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-14T04:29:09.512422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.687445Z","time spent":"824.968238ms","remote":"127.0.0.1:52294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-14T04:29:09.512522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.124864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-14T04:29:09.512545Z","caller":"traceutil/trace.go:171","msg":"trace[416648872] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:0; response_revision:10; }","duration":"800.195076ms","start":"2026-08-14T04:29:08.712343Z","end":"2026-08-14T04:29:09.512538Z","steps":["trace[416648872] 'agreement among raft nodes before linearized reading' (duration: 800.160935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:09.512573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:08.712314Z","time spent":"800.253478ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"info","ts":"2026-08-14T04:29:09.617646Z","caller":"traceutil/trace.go:171","msg":"trace[440641937] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"101.008206ms","start":"2026-08-14T04:29:09.516613Z","end":"2026-08-14T04:29:09.617621Z","steps":["trace[440641937] 'process raft request' (duration: 99.18172ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:29:09.617772Z","caller":"traceutil/trace.go:171","msg":"trace[1183299382] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"100.966835ms","start":"2026-08-14T04:29:09.51678Z","end":"2026-08-14T04:29:09.617747Z","steps":["trace[1183299382] 'process raft request' (duration: 100.787269ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:29:21.964477Z","caller":"traceutil/trace.go:171","msg":"trace[576311754] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"271.53114ms","start":"2026-08-14T04:29:21.692923Z","end":"2026-08-14T04:29:21.964454Z","steps":["trace[576311754] 'read index received' (duration: 271.330984ms)","trace[576311754] 'applied index is now lower than readState.Index' (duration: 199.146µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:29:21.964595Z","caller":"traceutil/trace.go:171","msg":"trace[775948065] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"278.499164ms","start":"2026-08-14T04:29:21.686086Z","end":"2026-08-14T04:29:21.964585Z","steps":["trace[775948065] 'process raft request' (duration: 278.232126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:21.964747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.811438ms","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-08-14T04:29:21.964777Z","caller":"traceutil/trace.go:171","msg":"trace[1093005490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"271.88336ms","start":"2026-08-14T04:29:21.692884Z","end":"2026-08-14T04:29:21.964767Z","steps":["trace[1093005490] 'agreement among raft nodes before linearized reading' (duration: 271.817268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:21.96491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.616151ms","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-08-14T04:29:21.964934Z","caller":"traceutil/trace.go:171","msg":"trace[1145441240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"269.677522ms","start":"2026-08-14T04:29:21.695249Z","end":"2026-08-14T04:29:21.964927Z","steps":["trace[1145441240] 'agreement among raft nodes before linearized reading' (duration: 269.621751ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:29:49.123829Z","caller":"traceutil/trace.go:171","msg":"trace[610533849] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"130.451172ms","start":"2026-08-14T04:29:48.993348Z","end":"2026-08-14T04:29:49.1238Z","steps":["trace[610533849] 'process raft request' (duration: 109.829846ms)","trace[610533849] 'compare' (duration: 20.482741ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:29:49.434501Z","caller":"traceutil/trace.go:171","msg":"trace[1822287870] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"110.58261ms","start":"2026-08-14T04:29:49.323862Z","end":"2026-08-14T04:29:49.434445Z","steps":["trace[1822287870] 'read index received' (duration: 109.507396ms)","trace[1822287870] 'applied index is now lower than readState.Index' (duration: 1.074174ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:29:49.43455Z","caller":"traceutil/trace.go:171","msg":"trace[353587817] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"125.742001ms","start":"2026-08-14T04:29:49.308739Z","end":"2026-08-14T04:29:49.434481Z","steps":["trace[353587817] 'process raft request' (duration: 124.900943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:49.434695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.808617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:29:49.434759Z","caller":"traceutil/trace.go:171","msg":"trace[1934858657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"110.924231ms","start":"2026-08-14T04:29:49.323823Z","end":"2026-08-14T04:29:49.434747Z","steps":["trace[1934858657] 'agreement among raft nodes before linearized reading' (duration: 110.785487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:55.616412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.58152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:29:55.616574Z","caller":"traceutil/trace.go:171","msg":"trace[480576143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"293.788617ms","start":"2026-08-14T04:29:55.322764Z","end":"2026-08-14T04:29:55.616552Z","steps":["trace[480576143] 'range keys from in-memory index tree' (duration: 293.507028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:55.616736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.531078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6c27k\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-14T04:29:55.616914Z","caller":"traceutil/trace.go:171","msg":"trace[1729522744] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6c27k; range_end:; response_count:1; response_revision:651; }","duration":"175.772646ms","start":"2026-08-14T04:29:55.44112Z","end":"2026-08-14T04:29:55.616893Z","steps":["trace[1729522744] 'range keys from in-memory index tree' (duration: 174.96562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:56.410144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.227547ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-14T04:29:56.471044Z","caller":"traceutil/trace.go:171","msg":"trace[1171203662] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"316.544722ms","start":"2026-08-14T04:29:56.154476Z","end":"2026-08-14T04:29:56.471021Z","steps":["trace[1171203662] 'read index received' (duration: 316.266343ms)","trace[1171203662] 'applied index is now lower than readState.Index' (duration: 277.199µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:29:56.471103Z","caller":"traceutil/trace.go:171","msg":"trace[1276377054] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"829.649419ms","start":"2026-08-14T04:29:55.641417Z","end":"2026-08-14T04:29:56.471066Z","steps":["trace[1276377054] 'process raft request' (duration: 829.436852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:56.471246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.42973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T04:29:56.471265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:55.641398Z","time spent":"829.786583ms","remote":"127.0.0.1:52372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:29:56.471314Z","caller":"traceutil/trace.go:171","msg":"trace[1296944952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"147.538223ms","start":"2026-08-14T04:29:56.323756Z","end":"2026-08-14T04:29:56.471294Z","steps":["trace[1296944952] 'agreement among raft nodes before linearized reading' (duration: 147.43202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:56.471379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.502894ms","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-08-14T04:29:56.471427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.910913ms","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-08-14T04:29:56.471427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.929145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T04:29:56.471481Z","caller":"traceutil/trace.go:171","msg":"trace[129570301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"317.053358ms","start":"2026-08-14T04:29:56.154414Z","end":"2026-08-14T04:29:56.471467Z","steps":["trace[129570301] 'agreement among raft nodes before linearized reading' (duration: 316.80191ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:29:56.471461Z","caller":"traceutil/trace.go:171","msg":"trace[265422082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:652; }","duration":"309.71987ms","start":"2026-08-14T04:29:56.161728Z","end":"2026-08-14T04:29:56.471448Z","steps":["trace[265422082] 'agreement among raft nodes before linearized reading' (duration: 309.435601ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:29:56.471491Z","caller":"traceutil/trace.go:171","msg":"trace[1342644941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"309.022548ms","start":"2026-08-14T04:29:56.162452Z","end":"2026-08-14T04:29:56.471475Z","steps":["trace[1342644941] 'agreement among raft nodes before linearized reading' (duration: 308.881044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:56.471517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:56.154317Z","time spent":"317.193592ms","remote":"127.0.0.1:52434","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-08-14T04:29:56.47153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:56.162422Z","time spent":"309.101041ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T04:29:56.471534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:56.161718Z","time spent":"309.804514ms","remote":"127.0.0.1:52434","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-08-14T04:29:57.722472Z","caller":"traceutil/trace.go:171","msg":"trace[613486691] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"397.878687ms","start":"2026-08-14T04:29:57.324571Z","end":"2026-08-14T04:29:57.72245Z","steps":["trace[613486691] 'read index received' (duration: 397.698352ms)","trace[613486691] 'applied index is now lower than readState.Index' (duration: 179.205µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:29:57.722524Z","caller":"traceutil/trace.go:171","msg":"trace[490977651] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"498.750235ms","start":"2026-08-14T04:29:57.223742Z","end":"2026-08-14T04:29:57.722493Z","steps":["trace[490977651] 'process raft request' (duration: 498.572699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:57.722587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.007131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:29:57.722629Z","caller":"traceutil/trace.go:171","msg":"trace[1057180729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"398.086514ms","start":"2026-08-14T04:29:57.324522Z","end":"2026-08-14T04:29:57.722609Z","steps":["trace[1057180729] 'agreement among raft nodes before linearized reading' (duration: 398.015071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:29:57.722675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:57.324501Z","time spent":"398.166196ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T04:29:57.722721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:29:57.223715Z","time spent":"498.890098ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:30:01.68755Z","caller":"traceutil/trace.go:171","msg":"trace[749854180] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"190.850983ms","start":"2026-08-14T04:30:01.496672Z","end":"2026-08-14T04:30:01.687523Z","steps":["trace[749854180] 'process raft request' (duration: 190.599935ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:01.687548Z","caller":"traceutil/trace.go:171","msg":"trace[1357804474] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"135.706662ms","start":"2026-08-14T04:30:01.551802Z","end":"2026-08-14T04:30:01.687509Z","steps":["trace[1357804474] 'read index received' (duration: 135.33585ms)","trace[1357804474] 'applied index is now lower than readState.Index' (duration: 369.032µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:30:01.687892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.058133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-08-14T04:30:01.687945Z","caller":"traceutil/trace.go:171","msg":"trace[489316759] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"136.171397ms","start":"2026-08-14T04:30:01.55176Z","end":"2026-08-14T04:30:01.687931Z","steps":["trace[489316759] 'agreement among raft nodes before linearized reading' (duration: 135.882977ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:10.480126Z","caller":"traceutil/trace.go:171","msg":"trace[1231117476] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"101.427215ms","start":"2026-08-14T04:30:10.378667Z","end":"2026-08-14T04:30:10.480095Z","steps":["trace[1231117476] 'process raft request' (duration: 80.788109ms)","trace[1231117476] 'compare' (duration: 20.489931ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:30:10.760366Z","caller":"traceutil/trace.go:171","msg":"trace[1682628608] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"110.25399ms","start":"2026-08-14T04:30:10.650075Z","end":"2026-08-14T04:30:10.760329Z","steps":["trace[1682628608] 'process raft request' (duration: 51.1288ms)","trace[1682628608] 'compare' (duration: 58.975255ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:30:10.760844Z","caller":"traceutil/trace.go:171","msg":"trace[661427250] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"109.775344ms","start":"2026-08-14T04:30:10.651032Z","end":"2026-08-14T04:30:10.760807Z","steps":["trace[661427250] 'process raft request' (duration: 109.572077ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:10.76088Z","caller":"traceutil/trace.go:171","msg":"trace[1675194795] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"109.428003ms","start":"2026-08-14T04:30:10.651439Z","end":"2026-08-14T04:30:10.760867Z","steps":["trace[1675194795] 'process raft request' (duration: 109.33622ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:10.760916Z","caller":"traceutil/trace.go:171","msg":"trace[1509646275] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"109.678341ms","start":"2026-08-14T04:30:10.651231Z","end":"2026-08-14T04:30:10.76091Z","steps":["trace[1509646275] 'process raft request' (duration: 109.492025ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:10.761397Z","caller":"traceutil/trace.go:171","msg":"trace[1189587607] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"109.748274ms","start":"2026-08-14T04:30:10.651641Z","end":"2026-08-14T04:30:10.761389Z","steps":["trace[1189587607] 'process raft request' (duration: 109.187206ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:10.761784Z","caller":"traceutil/trace.go:171","msg":"trace[842327413] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"108.513864ms","start":"2026-08-14T04:30:10.653262Z","end":"2026-08-14T04:30:10.761776Z","steps":["trace[842327413] 'process raft request' (duration: 108.08661ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:10.907879Z","caller":"traceutil/trace.go:171","msg":"trace[563551239] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"135.183305ms","start":"2026-08-14T04:30:10.772655Z","end":"2026-08-14T04:30:10.907839Z","steps":["trace[563551239] 'process raft request' (duration: 92.420445ms)","trace[563551239] 'compare' (duration: 42.572244ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:30:12.064644Z","caller":"traceutil/trace.go:171","msg":"trace[1736404269] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"189.628373ms","start":"2026-08-14T04:30:11.874981Z","end":"2026-08-14T04:30:12.064609Z","steps":["trace[1736404269] 'process raft request' (duration: 189.342974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:15.155606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.164528ms","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-08-14T04:30:15.155711Z","caller":"traceutil/trace.go:171","msg":"trace[641513801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:814; }","duration":"377.327113ms","start":"2026-08-14T04:30:14.778357Z","end":"2026-08-14T04:30:15.155684Z","steps":["trace[641513801] 'range keys from in-memory index tree' (duration: 376.982682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:15.156023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.325159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vg8rk\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-14T04:30:15.156078Z","caller":"traceutil/trace.go:171","msg":"trace[1614490364] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vg8rk; range_end:; response_count:1; response_revision:814; }","duration":"589.419472ms","start":"2026-08-14T04:30:14.566642Z","end":"2026-08-14T04:30:15.156061Z","steps":["trace[1614490364] 'range keys from in-memory index tree' (duration: 589.169744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:15.156061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.367752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-14T04:30:15.15611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:30:14.56662Z","time spent":"589.482064ms","remote":"127.0.0.1:52372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vg8rk\" "} {"level":"info","ts":"2026-08-14T04:30:15.156127Z","caller":"traceutil/trace.go:171","msg":"trace[253511117] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:814; }","duration":"507.471296ms","start":"2026-08-14T04:30:14.648639Z","end":"2026-08-14T04:30:15.15611Z","steps":["trace[253511117] 'count revisions from in-memory index tree' (duration: 507.231128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:15.156164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:30:14.648618Z","time spent":"507.536848ms","remote":"127.0.0.1:52502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-08-14T04:30:15.156225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.750836ms","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-08-14T04:30:15.156295Z","caller":"traceutil/trace.go:171","msg":"trace[1758562764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:814; }","duration":"377.869381ms","start":"2026-08-14T04:30:14.778405Z","end":"2026-08-14T04:30:15.156275Z","steps":["trace[1758562764] 'range keys from in-memory index tree' (duration: 377.580601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:15.156346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:30:14.778385Z","time spent":"377.950214ms","remote":"127.0.0.1:52434","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-08-14T04:30:15.155767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:30:14.778336Z","time spent":"377.415656ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T04:30:15.155736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.157966ms","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-08-14T04:30:15.156441Z","caller":"traceutil/trace.go:171","msg":"trace[1953762459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:814; }","duration":"376.91428ms","start":"2026-08-14T04:30:14.779515Z","end":"2026-08-14T04:30:15.156429Z","steps":["trace[1953762459] 'range keys from in-memory index tree' (duration: 375.975999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:15.156483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:30:14.779495Z","time spent":"376.976842ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T04:30:15.307984Z","caller":"traceutil/trace.go:171","msg":"trace[1568074584] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"146.678776ms","start":"2026-08-14T04:30:15.161285Z","end":"2026-08-14T04:30:15.307963Z","steps":["trace[1568074584] 'process raft request' (duration: 146.48551ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:15.308351Z","caller":"traceutil/trace.go:171","msg":"trace[1049885370] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"145.840469ms","start":"2026-08-14T04:30:15.162474Z","end":"2026-08-14T04:30:15.308314Z","steps":["trace[1049885370] 'process raft request' (duration: 145.449687ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:15.308769Z","caller":"traceutil/trace.go:171","msg":"trace[1494251761] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"145.092515ms","start":"2026-08-14T04:30:15.163647Z","end":"2026-08-14T04:30:15.30874Z","steps":["trace[1494251761] 'process raft request' (duration: 144.543587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:22.559622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.32667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T04:30:22.559697Z","caller":"traceutil/trace.go:171","msg":"trace[1682017472] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"167.996044ms","start":"2026-08-14T04:30:22.39169Z","end":"2026-08-14T04:30:22.559686Z","steps":["trace[1682017472] 'process raft request' (duration: 36.55507ms)","trace[1682017472] 'compare' (duration: 131.230297ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:30:22.565831Z","caller":"traceutil/trace.go:171","msg":"trace[742154250] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"174.068271ms","start":"2026-08-14T04:30:22.391747Z","end":"2026-08-14T04:30:22.565815Z","steps":["trace[742154250] 'process raft request' (duration: 173.885545ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:22.566202Z","caller":"traceutil/trace.go:171","msg":"trace[143213924] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"173.277615ms","start":"2026-08-14T04:30:22.392493Z","end":"2026-08-14T04:30:22.56577Z","steps":["trace[143213924] 'process raft request' (duration: 173.247404ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:22.678295Z","caller":"traceutil/trace.go:171","msg":"trace[1452566408] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"102.418047ms","start":"2026-08-14T04:30:22.575847Z","end":"2026-08-14T04:30:22.678265Z","steps":["trace[1452566408] 'process raft request' (duration: 101.788257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:25.550228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.244679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T04:30:25.550446Z","caller":"traceutil/trace.go:171","msg":"trace[2048233367] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"167.822509ms","start":"2026-08-14T04:30:25.382596Z","end":"2026-08-14T04:30:25.550419Z","steps":["trace[2048233367] 'process raft request' (duration: 66.254299ms)","trace[2048233367] 'compare' (duration: 101.108845ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:30:26.638404Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515506871800768,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T04:30:26.750726Z","caller":"traceutil/trace.go:171","msg":"trace[1826730490] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"613.038424ms","start":"2026-08-14T04:30:26.137666Z","end":"2026-08-14T04:30:26.750705Z","steps":["trace[1826730490] 'read index received' (duration: 612.899339ms)","trace[1826730490] 'applied index is now lower than readState.Index' (duration: 138.225µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:30:26.750875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.970286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:30:26.750914Z","caller":"traceutil/trace.go:171","msg":"trace[491221325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"427.065798ms","start":"2026-08-14T04:30:26.323838Z","end":"2026-08-14T04:30:26.750904Z","steps":["trace[491221325] 'agreement among raft nodes before linearized reading' (duration: 426.977806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:26.750949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:30:26.323817Z","time spent":"427.124221ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T04:30:26.750991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.305262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T04:30:26.751096Z","caller":"traceutil/trace.go:171","msg":"trace[2090059157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"613.463387ms","start":"2026-08-14T04:30:26.137615Z","end":"2026-08-14T04:30:26.751078Z","steps":["trace[2090059157] 'agreement among raft nodes before linearized reading' (duration: 613.198648ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:26.751078Z","caller":"traceutil/trace.go:171","msg":"trace[987555459] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"662.862702ms","start":"2026-08-14T04:30:26.088192Z","end":"2026-08-14T04:30:26.751055Z","steps":["trace[987555459] 'process raft request' (duration: 662.350886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:26.751145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:30:26.137598Z","time spent":"613.53851ms","remote":"127.0.0.1:52434","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-08-14T04:30:26.751865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:30:26.08817Z","time spent":"662.971066ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:30:27.049211Z","caller":"traceutil/trace.go:171","msg":"trace[26232604] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"293.8969ms","start":"2026-08-14T04:30:26.755266Z","end":"2026-08-14T04:30:27.049162Z","steps":["trace[26232604] 'process raft request' (duration: 293.745835ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:27.369331Z","caller":"traceutil/trace.go:171","msg":"trace[1502628966] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"107.666607ms","start":"2026-08-14T04:30:27.261637Z","end":"2026-08-14T04:30:27.369304Z","steps":["trace[1502628966] 'process raft request' (duration: 107.480331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:27.552156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.766102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T04:30:27.552451Z","caller":"traceutil/trace.go:171","msg":"trace[907954236] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"146.568913ms","start":"2026-08-14T04:30:27.405851Z","end":"2026-08-14T04:30:27.55242Z","steps":["trace[907954236] 'read index received' (duration: 37.375677ms)","trace[907954236] 'applied index is now lower than readState.Index' (duration: 109.191376ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:30:27.552466Z","caller":"traceutil/trace.go:171","msg":"trace[1642306061] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"165.088441ms","start":"2026-08-14T04:30:27.387337Z","end":"2026-08-14T04:30:27.552425Z","steps":["trace[1642306061] 'process raft request' (duration: 55.915255ms)","trace[1642306061] 'compare' (duration: 108.478923ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:30:27.552679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.80601ms","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-08-14T04:30:27.552765Z","caller":"traceutil/trace.go:171","msg":"trace[687393633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:938; }","duration":"146.934324ms","start":"2026-08-14T04:30:27.40581Z","end":"2026-08-14T04:30:27.552745Z","steps":["trace[687393633] 'agreement among raft nodes before linearized reading' (duration: 146.710547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:27.811728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.762211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T04:30:27.811901Z","caller":"traceutil/trace.go:171","msg":"trace[951821936] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"252.454111ms","start":"2026-08-14T04:30:27.559434Z","end":"2026-08-14T04:30:27.811888Z","steps":["trace[951821936] 'process raft request' (duration: 252.393869ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:27.811978Z","caller":"traceutil/trace.go:171","msg":"trace[1447025232] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"253.256717ms","start":"2026-08-14T04:30:27.558672Z","end":"2026-08-14T04:30:27.811929Z","steps":["trace[1447025232] 'process raft request' (duration: 118.204436ms)","trace[1447025232] 'compare' (duration: 134.618157ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:30:29.011895Z","caller":"traceutil/trace.go:171","msg":"trace[356347136] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"254.593161ms","start":"2026-08-14T04:30:28.757272Z","end":"2026-08-14T04:30:29.011865Z","steps":["trace[356347136] 'process raft request' (duration: 254.422695ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:29.014568Z","caller":"traceutil/trace.go:171","msg":"trace[2132304142] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"132.244059ms","start":"2026-08-14T04:30:28.882302Z","end":"2026-08-14T04:30:29.014546Z","steps":["trace[2132304142] 'process raft request' (duration: 132.134276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:31.988066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.564449ms","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-08-14T04:30:31.988133Z","caller":"traceutil/trace.go:171","msg":"trace[1486794122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:962; }","duration":"153.652822ms","start":"2026-08-14T04:30:31.834459Z","end":"2026-08-14T04:30:31.988112Z","steps":["trace[1486794122] 'range keys from in-memory index tree' (duration: 153.466365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:31.988066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.05825ms","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-08-14T04:30:31.988219Z","caller":"traceutil/trace.go:171","msg":"trace[1402245629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"152.227666ms","start":"2026-08-14T04:30:31.835977Z","end":"2026-08-14T04:30:31.988205Z","steps":["trace[1402245629] 'range keys from in-memory index tree' (duration: 151.921636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:31.988051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.865895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T04:30:31.988305Z","caller":"traceutil/trace.go:171","msg":"trace[539681938] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:962; }","duration":"266.204795ms","start":"2026-08-14T04:30:31.722087Z","end":"2026-08-14T04:30:31.988292Z","steps":["trace[539681938] 'count revisions from in-memory index tree' (duration: 265.774612ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:33.274313Z","caller":"traceutil/trace.go:171","msg":"trace[1188540437] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"166.672471ms","start":"2026-08-14T04:30:33.10762Z","end":"2026-08-14T04:30:33.274292Z","steps":["trace[1188540437] 'process raft request' (duration: 130.292316ms)","trace[1188540437] 'compare' (duration: 36.289372ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:30:34.320276Z","caller":"traceutil/trace.go:171","msg":"trace[450238401] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"314.300888ms","start":"2026-08-14T04:30:34.005947Z","end":"2026-08-14T04:30:34.320248Z","steps":["trace[450238401] 'process raft request' (duration: 314.151373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:34.320429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:30:34.005931Z","time spent":"314.421123ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:30:34.387233Z","caller":"traceutil/trace.go:171","msg":"trace[421958970] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"134.580215ms","start":"2026-08-14T04:30:34.252628Z","end":"2026-08-14T04:30:34.387208Z","steps":["trace[421958970] 'process raft request' (duration: 134.477482ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:34.387219Z","caller":"traceutil/trace.go:171","msg":"trace[2115755979] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"379.901597ms","start":"2026-08-14T04:30:34.007283Z","end":"2026-08-14T04:30:34.387184Z","steps":["trace[2115755979] 'process raft request' (duration: 379.70683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:30:34.387501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:30:34.007261Z","time spent":"380.158785ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:30:35.362598Z","caller":"traceutil/trace.go:171","msg":"trace[1978579664] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"253.582468ms","start":"2026-08-14T04:30:35.108984Z","end":"2026-08-14T04:30:35.362566Z","steps":["trace[1978579664] 'process raft request' (duration: 253.361471ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:35.715312Z","caller":"traceutil/trace.go:171","msg":"trace[857264329] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"225.490001ms","start":"2026-08-14T04:30:35.489796Z","end":"2026-08-14T04:30:35.715286Z","steps":["trace[857264329] 'read index received' (duration: 175.89863ms)","trace[857264329] 'applied index is now lower than readState.Index' (duration: 49.589771ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:30:35.715565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.818818ms","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-08-14T04:30:35.715562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.75715ms","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-08-14T04:30:35.715599Z","caller":"traceutil/trace.go:171","msg":"trace[986627183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:974; }","duration":"123.920381ms","start":"2026-08-14T04:30:35.591669Z","end":"2026-08-14T04:30:35.71559Z","steps":["trace[986627183] 'agreement among raft nodes before linearized reading' (duration: 123.791017ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:30:35.71561Z","caller":"traceutil/trace.go:171","msg":"trace[2133929695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"225.865513ms","start":"2026-08-14T04:30:35.489734Z","end":"2026-08-14T04:30:35.715599Z","steps":["trace[2133929695] 'agreement among raft nodes before linearized reading' (duration: 225.694328ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:03.721476Z","caller":"traceutil/trace.go:171","msg":"trace[2114405381] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"242.676538ms","start":"2026-08-14T04:31:03.478775Z","end":"2026-08-14T04:31:03.721452Z","steps":["trace[2114405381] 'read index received' (duration: 242.472861ms)","trace[2114405381] 'applied index is now lower than readState.Index' (duration: 202.817µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:31:03.721554Z","caller":"traceutil/trace.go:171","msg":"trace[528005331] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"265.178114ms","start":"2026-08-14T04:31:03.456344Z","end":"2026-08-14T04:31:03.721522Z","steps":["trace[528005331] 'process raft request' (duration: 264.976357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:03.7217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.910185ms","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-08-14T04:31:03.721784Z","caller":"traceutil/trace.go:171","msg":"trace[1044026838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1070; }","duration":"243.05949ms","start":"2026-08-14T04:31:03.478713Z","end":"2026-08-14T04:31:03.721772Z","steps":["trace[1044026838] 'agreement among raft nodes before linearized reading' (duration: 242.857183ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:04.09083Z","caller":"traceutil/trace.go:171","msg":"trace[5555828] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"148.179125ms","start":"2026-08-14T04:31:03.942624Z","end":"2026-08-14T04:31:04.090804Z","steps":["trace[5555828] 'process raft request' (duration: 117.384691ms)","trace[5555828] 'compare' (duration: 30.633939ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:05.700795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187187659s","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-08-14T04:31:05.700839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.539521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:05.700885Z","caller":"traceutil/trace.go:171","msg":"trace[345789088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1073; }","duration":"1.187330094s","start":"2026-08-14T04:31:04.513529Z","end":"2026-08-14T04:31:05.700859Z","steps":["trace[345789088] 'range keys from in-memory index tree' (duration: 1.187009794s)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:05.700922Z","caller":"traceutil/trace.go:171","msg":"trace[1260844432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"616.672795ms","start":"2026-08-14T04:31:05.084227Z","end":"2026-08-14T04:31:05.7009Z","steps":["trace[1260844432] 'range keys from in-memory index tree' (duration: 616.459818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:05.700939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:04.513507Z","time spent":"1.187422347s","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T04:31:05.700961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:05.084206Z","time spent":"616.747528ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T04:31:05.701088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078040714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:05.701169Z","caller":"traceutil/trace.go:171","msg":"trace[1875782905] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1073; }","duration":"1.078159278s","start":"2026-08-14T04:31:04.622986Z","end":"2026-08-14T04:31:05.701145Z","steps":["trace[1875782905] 'count revisions from in-memory index tree' (duration: 1.077956161s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:05.701213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:04.622974Z","time spent":"1.078227611s","remote":"127.0.0.1:39496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-14T04:31:05.701366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.441997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:05.701403Z","caller":"traceutil/trace.go:171","msg":"trace[1524338595] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1073; }","duration":"982.513709ms","start":"2026-08-14T04:31:04.718876Z","end":"2026-08-14T04:31:05.70139Z","steps":["trace[1524338595] 'count revisions from in-memory index tree' (duration: 982.362764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:05.70144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:04.718856Z","time spent":"982.575551ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-08-14T04:31:05.701416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256296041s","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-08-14T04:31:05.70153Z","caller":"traceutil/trace.go:171","msg":"trace[398916645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1073; }","duration":"1.256458066s","start":"2026-08-14T04:31:04.44504Z","end":"2026-08-14T04:31:05.701499Z","steps":["trace[398916645] 'range keys from in-memory index tree' (duration: 1.256028412s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:05.70272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:04.445008Z","time spent":"1.25656186s","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T04:31:05.825696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515506871801130,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T04:31:05.980954Z","caller":"traceutil/trace.go:171","msg":"trace[988879005] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"741.64577ms","start":"2026-08-14T04:31:05.239271Z","end":"2026-08-14T04:31:05.980917Z","steps":["trace[988879005] 'process raft request' (duration: 741.430963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:05.981176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:05.239223Z","time spent":"741.833846ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T04:31:06.340489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.293733ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-14T04:31:06.340966Z","caller":"traceutil/trace.go:171","msg":"trace[281271454] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1115; }","duration":"1.016107223s","start":"2026-08-14T04:31:05.324828Z","end":"2026-08-14T04:31:06.340935Z","steps":["trace[281271454] 'read index received' (duration: 655.993484ms)","trace[281271454] 'applied index is now lower than readState.Index' (duration: 360.111779ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:31:06.34096Z","caller":"traceutil/trace.go:171","msg":"trace[1284455840] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"633.784257ms","start":"2026-08-14T04:31:05.70713Z","end":"2026-08-14T04:31:06.340915Z","steps":["trace[1284455840] 'process raft request' (duration: 633.508138ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:06.34102Z","caller":"traceutil/trace.go:171","msg":"trace[818256597] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"633.830028ms","start":"2026-08-14T04:31:05.707132Z","end":"2026-08-14T04:31:06.340962Z","steps":["trace[818256597] 'process raft request' (duration: 633.660193ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:06.341053Z","caller":"traceutil/trace.go:171","msg":"trace[1387810393] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"614.759412ms","start":"2026-08-14T04:31:05.726254Z","end":"2026-08-14T04:31:06.341013Z","steps":["trace[1387810393] 'process raft request' (duration: 614.599667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:06.34115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01630904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T04:31:06.341183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:05.7071Z","time spent":"633.992383ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:31:06.341195Z","caller":"traceutil/trace.go:171","msg":"trace[1598839067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"1.016409243s","start":"2026-08-14T04:31:05.324773Z","end":"2026-08-14T04:31:06.341182Z","steps":["trace[1598839067] 'agreement among raft nodes before linearized reading' (duration: 1.0163146s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:06.341218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:05.707106Z","time spent":"633.955172ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T04:31:06.341243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:05.726216Z","time spent":"614.928868ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T04:31:06.341278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:05.324749Z","time spent":"1.016511177s","remote":"127.0.0.1:52194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T04:31:06.341366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.908491ms","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-08-14T04:31:06.341402Z","caller":"traceutil/trace.go:171","msg":"trace[501402751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1077; }","duration":"581.973423ms","start":"2026-08-14T04:31:05.759416Z","end":"2026-08-14T04:31:06.341389Z","steps":["trace[501402751] 'agreement among raft nodes before linearized reading' (duration: 581.814598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:06.341444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:05.759401Z","time spent":"582.034126ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T04:31:06.341479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.180731ms","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-08-14T04:31:06.341507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.747148ms","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-08-14T04:31:06.341522Z","caller":"traceutil/trace.go:171","msg":"trace[1642795386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"246.288034ms","start":"2026-08-14T04:31:06.095221Z","end":"2026-08-14T04:31:06.34151Z","steps":["trace[1642795386] 'agreement among raft nodes before linearized reading' (duration: 246.127269ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:06.341545Z","caller":"traceutil/trace.go:171","msg":"trace[1114454179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"398.8325ms","start":"2026-08-14T04:31:05.942701Z","end":"2026-08-14T04:31:06.341533Z","steps":["trace[1114454179] 'agreement among raft nodes before linearized reading' (duration: 398.685095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:06.341576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:05.942676Z","time spent":"398.893212ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T04:31:06.341633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.250026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:06.341672Z","caller":"traceutil/trace.go:171","msg":"trace[1279554600] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1077; }","duration":"143.317089ms","start":"2026-08-14T04:31:06.198342Z","end":"2026-08-14T04:31:06.341659Z","steps":["trace[1279554600] 'agreement among raft nodes before linearized reading' (duration: 143.255217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:06.901354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.598417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T04:31:06.901579Z","caller":"traceutil/trace.go:171","msg":"trace[1811231862] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"554.370323ms","start":"2026-08-14T04:31:06.347186Z","end":"2026-08-14T04:31:06.901556Z","steps":["trace[1811231862] 'process raft request' (duration: 53.429095ms)","trace[1811231862] 'compare' (duration: 500.143322ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:06.901662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:06.347151Z","time spent":"554.474856ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:31:06.902065Z","caller":"traceutil/trace.go:171","msg":"trace[240290133] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1120; }","duration":"552.044608ms","start":"2026-08-14T04:31:06.349998Z","end":"2026-08-14T04:31:06.902043Z","steps":["trace[240290133] 'read index received' (duration: 50.742728ms)","trace[240290133] 'applied index is now lower than readState.Index' (duration: 501.30089ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:31:06.902146Z","caller":"traceutil/trace.go:171","msg":"trace[435841490] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"554.792916ms","start":"2026-08-14T04:31:06.347302Z","end":"2026-08-14T04:31:06.902095Z","steps":["trace[435841490] 'process raft request' (duration: 554.180317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:06.902204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.195822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:06.902497Z","caller":"traceutil/trace.go:171","msg":"trace[268812691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"552.530914ms","start":"2026-08-14T04:31:06.349942Z","end":"2026-08-14T04:31:06.902473Z","steps":["trace[268812691] 'agreement among raft nodes before linearized reading' (duration: 552.211083ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:06.902181Z","caller":"traceutil/trace.go:171","msg":"trace[1044588553] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"554.555318ms","start":"2026-08-14T04:31:06.347596Z","end":"2026-08-14T04:31:06.902152Z","steps":["trace[1044588553] 'process raft request' (duration: 554.364732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:06.902592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:06.349925Z","time spent":"552.639886ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T04:31:06.902634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:06.347585Z","time spent":"554.987693ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T04:31:06.90265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:06.347268Z","time spent":"555.20648ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:31:08.485487Z","caller":"traceutil/trace.go:171","msg":"trace[893427767] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"134.774783ms","start":"2026-08-14T04:31:08.350688Z","end":"2026-08-14T04:31:08.485463Z","steps":["trace[893427767] 'process raft request' (duration: 134.713041ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:08.485843Z","caller":"traceutil/trace.go:171","msg":"trace[721253578] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"136.512768ms","start":"2026-08-14T04:31:08.349304Z","end":"2026-08-14T04:31:08.485817Z","steps":["trace[721253578] 'process raft request' (duration: 134.675429ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:23.145896Z","caller":"traceutil/trace.go:171","msg":"trace[1605534727] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"267.023883ms","start":"2026-08-14T04:31:22.878856Z","end":"2026-08-14T04:31:23.145879Z","steps":["trace[1605534727] 'process raft request' (duration: 266.801736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:23.146076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.267254ms","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-08-14T04:31:23.146128Z","caller":"traceutil/trace.go:171","msg":"trace[1488849472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1182; }","duration":"168.370377ms","start":"2026-08-14T04:31:22.977728Z","end":"2026-08-14T04:31:23.146099Z","steps":["trace[1488849472] 'agreement among raft nodes before linearized reading' (duration: 168.193552ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:23.145873Z","caller":"traceutil/trace.go:171","msg":"trace[1016469880] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1229; }","duration":"168.041156ms","start":"2026-08-14T04:31:22.977803Z","end":"2026-08-14T04:31:23.145844Z","steps":["trace[1016469880] 'read index received' (duration: 167.84205ms)","trace[1016469880] 'applied index is now lower than readState.Index' (duration: 198.026µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:23.146281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.519198ms","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-08-14T04:31:23.146302Z","caller":"traceutil/trace.go:171","msg":"trace[1433164247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1182; }","duration":"162.55282ms","start":"2026-08-14T04:31:22.983743Z","end":"2026-08-14T04:31:23.146296Z","steps":["trace[1433164247] 'agreement among raft nodes before linearized reading' (duration: 162.487398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:23.146412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.990631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:23.146439Z","caller":"traceutil/trace.go:171","msg":"trace[651607253] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1182; }","duration":"101.083874ms","start":"2026-08-14T04:31:23.045346Z","end":"2026-08-14T04:31:23.14643Z","steps":["trace[651607253] 'agreement among raft nodes before linearized reading' (duration: 101.032142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:23.1465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.600061ms","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-08-14T04:31:23.14652Z","caller":"traceutil/trace.go:171","msg":"trace[192029163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"162.683254ms","start":"2026-08-14T04:31:22.983831Z","end":"2026-08-14T04:31:23.146514Z","steps":["trace[192029163] 'agreement among raft nodes before linearized reading' (duration: 162.615632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:23.491532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.090375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T04:31:23.491699Z","caller":"traceutil/trace.go:171","msg":"trace[546547795] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"342.065117ms","start":"2026-08-14T04:31:23.149607Z","end":"2026-08-14T04:31:23.491672Z","steps":["trace[546547795] 'process raft request' (duration: 218.713113ms)","trace[546547795] 'compare' (duration: 122.228117ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:23.491784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:23.149588Z","time spent":"342.16429ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:31:23.509302Z","caller":"traceutil/trace.go:171","msg":"trace[1677822608] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1230; }","duration":"183.709883ms","start":"2026-08-14T04:31:23.325565Z","end":"2026-08-14T04:31:23.509275Z","steps":["trace[1677822608] 'read index received' (duration: 42.763951ms)","trace[1677822608] 'applied index is now lower than readState.Index' (duration: 140.944512ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:31:23.509323Z","caller":"traceutil/trace.go:171","msg":"trace[1397170730] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"359.302743ms","start":"2026-08-14T04:31:23.149987Z","end":"2026-08-14T04:31:23.50929Z","steps":["trace[1397170730] 'process raft request' (duration: 359.116127ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:23.509372Z","caller":"traceutil/trace.go:171","msg":"trace[1660471199] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"359.147478ms","start":"2026-08-14T04:31:23.15019Z","end":"2026-08-14T04:31:23.509338Z","steps":["trace[1660471199] 'process raft request' (duration: 359.041724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:23.509421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.848637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:23.509454Z","caller":"traceutil/trace.go:171","msg":"trace[637448345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"183.93952ms","start":"2026-08-14T04:31:23.325507Z","end":"2026-08-14T04:31:23.509446Z","steps":["trace[637448345] 'agreement among raft nodes before linearized reading' (duration: 183.863428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:23.509484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:23.14998Z","time spent":"359.409317ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T04:31:23.509516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:23.150155Z","time spent":"359.289193ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T04:31:25.059162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515506871801378,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T04:31:25.159496Z","caller":"traceutil/trace.go:171","msg":"trace[2053025279] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"709.40868ms","start":"2026-08-14T04:31:24.450067Z","end":"2026-08-14T04:31:25.159476Z","steps":["trace[2053025279] 'process raft request' (duration: 709.266805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:25.159643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:24.450043Z","time spent":"709.516594ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:31:25.159593Z","caller":"traceutil/trace.go:171","msg":"trace[774889073] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"601.465664ms","start":"2026-08-14T04:31:24.558071Z","end":"2026-08-14T04:31:25.159537Z","steps":["trace[774889073] 'read index received' (duration: 601.198865ms)","trace[774889073] 'applied index is now lower than readState.Index' (duration: 262.259µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:25.159837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.751053ms","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-08-14T04:31:25.159923Z","caller":"traceutil/trace.go:171","msg":"trace[351036115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1186; }","duration":"601.874878ms","start":"2026-08-14T04:31:24.558028Z","end":"2026-08-14T04:31:25.159903Z","steps":["trace[351036115] 'agreement among raft nodes before linearized reading' (duration: 601.64982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:25.159966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.510495ms","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":"warn","ts":"2026-08-14T04:31:25.159968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:24.558013Z","time spent":"601.946449ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T04:31:25.159989Z","caller":"traceutil/trace.go:171","msg":"trace[969522167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1186; }","duration":"601.545976ms","start":"2026-08-14T04:31:24.558437Z","end":"2026-08-14T04:31:25.159983Z","steps":["trace[969522167] 'agreement among raft nodes before linearized reading' (duration: 601.450363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:25.160007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:24.558423Z","time spent":"601.580158ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T04:31:25.160038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.594374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:25.160089Z","caller":"traceutil/trace.go:171","msg":"trace[1802619130] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1186; }","duration":"425.76213ms","start":"2026-08-14T04:31:24.734314Z","end":"2026-08-14T04:31:25.160076Z","steps":["trace[1802619130] 'agreement among raft nodes before linearized reading' (duration: 425.609365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:25.16017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:24.73409Z","time spent":"426.06809ms","remote":"127.0.0.1:52326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-08-14T04:31:25.457279Z","caller":"traceutil/trace.go:171","msg":"trace[431349679] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"131.58816ms","start":"2026-08-14T04:31:25.325651Z","end":"2026-08-14T04:31:25.45724Z","steps":["trace[431349679] 'read index received' (duration: 130.912988ms)","trace[431349679] 'applied index is now lower than readState.Index' (duration: 673.752µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:25.457491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.800006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:25.457553Z","caller":"traceutil/trace.go:171","msg":"trace[402131586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"131.960301ms","start":"2026-08-14T04:31:25.32558Z","end":"2026-08-14T04:31:25.45754Z","steps":["trace[402131586] 'agreement among raft nodes before linearized reading' (duration: 131.816837ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:25.457745Z","caller":"traceutil/trace.go:171","msg":"trace[1920683628] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"292.992592ms","start":"2026-08-14T04:31:25.164709Z","end":"2026-08-14T04:31:25.457701Z","steps":["trace[1920683628] 'process raft request' (duration: 292.181545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:39.769705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515506871801494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T04:31:40.270437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515506871801494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T04:31:40.271945Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063560497s","expected-duration":"1s"} {"level":"info","ts":"2026-08-14T04:31:40.272256Z","caller":"traceutil/trace.go:171","msg":"trace[304979535] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"1.003078133s","start":"2026-08-14T04:31:39.269155Z","end":"2026-08-14T04:31:40.272233Z","steps":["trace[304979535] 'read index received' (duration: 1.002897277s)","trace[304979535] 'applied index is now lower than readState.Index' (duration: 179.986µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:31:40.272509Z","caller":"traceutil/trace.go:171","msg":"trace[196567532] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"1.064177986s","start":"2026-08-14T04:31:39.208304Z","end":"2026-08-14T04:31:40.272481Z","steps":["trace[196567532] 'process raft request' (duration: 1.063806834s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:40.272616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:39.208286Z","time spent":"1.064259919s","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T04:31:40.272937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003783017s","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-08-14T04:31:40.272968Z","caller":"traceutil/trace.go:171","msg":"trace[1126263352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1232; }","duration":"1.003837088s","start":"2026-08-14T04:31:39.269122Z","end":"2026-08-14T04:31:40.272959Z","steps":["trace[1126263352] 'agreement among raft nodes before linearized reading' (duration: 1.003699873s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:40.27301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:39.2691Z","time spent":"1.0038907s","remote":"127.0.0.1:52434","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-08-14T04:31:40.27316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.979436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:40.273186Z","caller":"traceutil/trace.go:171","msg":"trace[1911355389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"949.016688ms","start":"2026-08-14T04:31:39.324161Z","end":"2026-08-14T04:31:40.273178Z","steps":["trace[1911355389] 'agreement among raft nodes before linearized reading' (duration: 948.974896ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:40.273209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:39.324155Z","time spent":"949.049309ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T04:31:40.273447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.460596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T04:31:40.273483Z","caller":"traceutil/trace.go:171","msg":"trace[361088832] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1232; }","duration":"336.535238ms","start":"2026-08-14T04:31:39.936939Z","end":"2026-08-14T04:31:40.273475Z","steps":["trace[361088832] 'agreement among raft nodes before linearized reading' (duration: 336.467656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:40.273511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:39.936922Z","time spent":"336.581509ms","remote":"127.0.0.1:52502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-08-14T04:31:40.273709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.980709ms","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-08-14T04:31:40.273755Z","caller":"traceutil/trace.go:171","msg":"trace[163166023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"697.03325ms","start":"2026-08-14T04:31:39.576701Z","end":"2026-08-14T04:31:40.273735Z","steps":["trace[163166023] 'agreement among raft nodes before linearized reading' (duration: 696.933527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:40.273782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:39.576692Z","time spent":"697.084612ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T04:31:40.273958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.288538ms","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-08-14T04:31:40.273993Z","caller":"traceutil/trace.go:171","msg":"trace[208474316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1232; }","duration":"701.345249ms","start":"2026-08-14T04:31:39.57264Z","end":"2026-08-14T04:31:40.273985Z","steps":["trace[208474316] 'agreement among raft nodes before linearized reading' (duration: 701.257336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:40.27402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:39.57263Z","time spent":"701.384801ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T04:31:40.274209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.636827ms","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-08-14T04:31:40.274243Z","caller":"traceutil/trace.go:171","msg":"trace[601826601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"722.716299ms","start":"2026-08-14T04:31:39.551519Z","end":"2026-08-14T04:31:40.274235Z","steps":["trace[601826601] 'agreement among raft nodes before linearized reading' (duration: 722.636136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:40.274269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:39.551505Z","time spent":"722.758231ms","remote":"127.0.0.1:52434","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-08-14T04:31:40.274429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.179287ms","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-08-14T04:31:40.274462Z","caller":"traceutil/trace.go:171","msg":"trace[1437329139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"737.241889ms","start":"2026-08-14T04:31:39.537213Z","end":"2026-08-14T04:31:40.274455Z","steps":["trace[1437329139] 'agreement among raft nodes before linearized reading' (duration: 737.162867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:40.274489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:39.537196Z","time spent":"737.28744ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T04:31:40.274638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.459812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:40.274682Z","caller":"traceutil/trace.go:171","msg":"trace[1716198495] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1232; }","duration":"901.531914ms","start":"2026-08-14T04:31:39.373141Z","end":"2026-08-14T04:31:40.274673Z","steps":["trace[1716198495] 'agreement among raft nodes before linearized reading' (duration: 901.470622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:40.274711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:39.373127Z","time spent":"901.576805ms","remote":"127.0.0.1:39480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-14T04:31:40.478233Z","caller":"traceutil/trace.go:171","msg":"trace[229523600] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"199.059228ms","start":"2026-08-14T04:31:40.279155Z","end":"2026-08-14T04:31:40.478214Z","steps":["trace[229523600] 'process raft request' (duration: 198.920404ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:40.504476Z","caller":"traceutil/trace.go:171","msg":"trace[908706854] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"225.12409ms","start":"2026-08-14T04:31:40.279336Z","end":"2026-08-14T04:31:40.504461Z","steps":["trace[908706854] 'process raft request' (duration: 225.028897ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:40.504615Z","caller":"traceutil/trace.go:171","msg":"trace[1929967960] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"223.352173ms","start":"2026-08-14T04:31:40.281259Z","end":"2026-08-14T04:31:40.504611Z","steps":["trace[1929967960] 'process raft request' (duration: 223.182427ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:40.504706Z","caller":"traceutil/trace.go:171","msg":"trace[694345704] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1283; }","duration":"177.643157ms","start":"2026-08-14T04:31:40.327057Z","end":"2026-08-14T04:31:40.5047Z","steps":["trace[694345704] 'read index received' (duration: 151.100989ms)","trace[694345704] 'applied index is now lower than readState.Index' (duration: 26.541318ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:40.504799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.726819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-14T04:31:40.504814Z","caller":"traceutil/trace.go:171","msg":"trace[2137815405] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1237; }","duration":"177.777501ms","start":"2026-08-14T04:31:40.327032Z","end":"2026-08-14T04:31:40.504809Z","steps":["trace[2137815405] 'agreement among raft nodes before linearized reading' (duration: 177.687208ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:40.504906Z","caller":"traceutil/trace.go:171","msg":"trace[1030061089] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"223.417475ms","start":"2026-08-14T04:31:40.281482Z","end":"2026-08-14T04:31:40.504899Z","steps":["trace[1030061089] 'process raft request' (duration: 223.111215ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:40.504955Z","caller":"traceutil/trace.go:171","msg":"trace[1120330771] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"223.382464ms","start":"2026-08-14T04:31:40.281568Z","end":"2026-08-14T04:31:40.504951Z","steps":["trace[1120330771] 'process raft request' (duration: 223.113475ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:40.74872Z","caller":"traceutil/trace.go:171","msg":"trace[2119373536] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"188.771666ms","start":"2026-08-14T04:31:40.559919Z","end":"2026-08-14T04:31:40.748691Z","steps":["trace[2119373536] 'process raft request' (duration: 173.284516ms)","trace[2119373536] 'compare' (duration: 15.363026ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:41.42315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.2957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:41.423225Z","caller":"traceutil/trace.go:171","msg":"trace[1129614912] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1242; }","duration":"214.406924ms","start":"2026-08-14T04:31:41.208794Z","end":"2026-08-14T04:31:41.423201Z","steps":["trace[1129614912] 'range keys from in-memory index tree' (duration: 214.230229ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:42.470468Z","caller":"traceutil/trace.go:171","msg":"trace[278675404] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"187.619888ms","start":"2026-08-14T04:31:42.282822Z","end":"2026-08-14T04:31:42.470442Z","steps":["trace[278675404] 'process raft request' (duration: 187.439313ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:42.60088Z","caller":"traceutil/trace.go:171","msg":"trace[799535122] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"113.465474ms","start":"2026-08-14T04:31:42.487392Z","end":"2026-08-14T04:31:42.600858Z","steps":["trace[799535122] 'process raft request' (duration: 98.486811ms)","trace[799535122] 'compare' (duration: 14.725675ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:31:43.395671Z","caller":"traceutil/trace.go:171","msg":"trace[546586095] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"189.778339ms","start":"2026-08-14T04:31:43.205856Z","end":"2026-08-14T04:31:43.395635Z","steps":["trace[546586095] 'process raft request' (duration: 98.830752ms)","trace[546586095] 'compare' (duration: 90.719309ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:43.716065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.356991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T04:31:43.716137Z","caller":"traceutil/trace.go:171","msg":"trace[1216252265] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1253; }","duration":"135.465575ms","start":"2026-08-14T04:31:43.580648Z","end":"2026-08-14T04:31:43.716114Z","steps":["trace[1216252265] 'count revisions from in-memory index tree' (duration: 135.254058ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:45.121514Z","caller":"traceutil/trace.go:171","msg":"trace[1217524992] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"446.409836ms","start":"2026-08-14T04:31:44.675084Z","end":"2026-08-14T04:31:45.121493Z","steps":["trace[1217524992] 'read index received' (duration: 368.760619ms)","trace[1217524992] 'applied index is now lower than readState.Index' (duration: 77.648297ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:31:45.121602Z","caller":"traceutil/trace.go:171","msg":"trace[1301891888] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"509.39202ms","start":"2026-08-14T04:31:44.612202Z","end":"2026-08-14T04:31:45.121594Z","steps":["trace[1301891888] 'process raft request' (duration: 431.759403ms)","trace[1301891888] 'compare' (duration: 77.168932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:45.121676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:44.612185Z","time spent":"509.436292ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T04:31:45.121721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.995856ms","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-08-14T04:31:45.121778Z","caller":"traceutil/trace.go:171","msg":"trace[979130615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1255; }","duration":"434.093369ms","start":"2026-08-14T04:31:44.687671Z","end":"2026-08-14T04:31:45.121764Z","steps":["trace[979130615] 'agreement among raft nodes before linearized reading' (duration: 433.937714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:45.121808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:44.687648Z","time spent":"434.153071ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T04:31:45.12197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.321744ms","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-08-14T04:31:45.121998Z","caller":"traceutil/trace.go:171","msg":"trace[821545183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1255; }","duration":"433.366285ms","start":"2026-08-14T04:31:44.688623Z","end":"2026-08-14T04:31:45.12199Z","steps":["trace[821545183] 'agreement among raft nodes before linearized reading' (duration: 433.289433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:45.122011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.929364ms","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-08-14T04:31:45.122023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:44.688613Z","time spent":"433.403467ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T04:31:45.122041Z","caller":"traceutil/trace.go:171","msg":"trace[653045081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1255; }","duration":"446.988926ms","start":"2026-08-14T04:31:44.675045Z","end":"2026-08-14T04:31:45.122034Z","steps":["trace[653045081] 'agreement among raft nodes before linearized reading' (duration: 446.921003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:45.122058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:44.67503Z","time spent":"447.024066ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T04:31:45.122231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.020067ms","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-08-14T04:31:45.122267Z","caller":"traceutil/trace.go:171","msg":"trace[1536420606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1255; }","duration":"434.075269ms","start":"2026-08-14T04:31:44.688183Z","end":"2026-08-14T04:31:45.122258Z","steps":["trace[1536420606] 'agreement among raft nodes before linearized reading' (duration: 433.989646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:45.122292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:31:44.688174Z","time spent":"434.11202ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-14T04:31:49.376727Z","caller":"traceutil/trace.go:171","msg":"trace[710823402] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"232.320712ms","start":"2026-08-14T04:31:49.144384Z","end":"2026-08-14T04:31:49.376705Z","steps":["trace[710823402] 'process raft request' (duration: 232.074215ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:49.3767Z","caller":"traceutil/trace.go:171","msg":"trace[607686028] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"205.212308ms","start":"2026-08-14T04:31:49.171466Z","end":"2026-08-14T04:31:49.376678Z","steps":["trace[607686028] 'read index received' (duration: 204.919898ms)","trace[607686028] 'applied index is now lower than readState.Index' (duration: 288.61µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:31:49.377132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.767443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-14T04:31:49.377173Z","caller":"traceutil/trace.go:171","msg":"trace[1377916037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"195.841004ms","start":"2026-08-14T04:31:49.181321Z","end":"2026-08-14T04:31:49.377162Z","steps":["trace[1377916037] 'agreement among raft nodes before linearized reading' (duration: 195.70232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:49.377383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.879419ms","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-08-14T04:31:49.377511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.549453ms","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-08-14T04:31:49.377538Z","caller":"traceutil/trace.go:171","msg":"trace[251275574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"190.584265ms","start":"2026-08-14T04:31:49.186946Z","end":"2026-08-14T04:31:49.37753Z","steps":["trace[251275574] 'agreement among raft nodes before linearized reading' (duration: 190.515033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:49.377397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.386999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T04:31:49.377702Z","caller":"traceutil/trace.go:171","msg":"trace[1614947416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"190.716529ms","start":"2026-08-14T04:31:49.186974Z","end":"2026-08-14T04:31:49.377691Z","steps":["trace[1614947416] 'agreement among raft nodes before linearized reading' (duration: 190.364238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:31:49.377441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.446073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T04:31:49.377778Z","caller":"traceutil/trace.go:171","msg":"trace[1351446000] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1295; }","duration":"191.801084ms","start":"2026-08-14T04:31:49.185967Z","end":"2026-08-14T04:31:49.377768Z","steps":["trace[1351446000] 'agreement among raft nodes before linearized reading' (duration: 191.441062ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:49.377646Z","caller":"traceutil/trace.go:171","msg":"trace[1592564228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"206.086015ms","start":"2026-08-14T04:31:49.171422Z","end":"2026-08-14T04:31:49.377508Z","steps":["trace[1592564228] 'agreement among raft nodes before linearized reading' (duration: 205.348262ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:31:50.769316Z","caller":"traceutil/trace.go:171","msg":"trace[701739810] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"198.862523ms","start":"2026-08-14T04:31:50.570425Z","end":"2026-08-14T04:31:50.769288Z","steps":["trace[701739810] 'process raft request' (duration: 198.604414ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:33:51.349914Z","caller":"traceutil/trace.go:171","msg":"trace[1903260643] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"125.156233ms","start":"2026-08-14T04:33:51.224727Z","end":"2026-08-14T04:33:51.349884Z","steps":["trace[1903260643] 'read index received' (duration: 124.870094ms)","trace[1903260643] 'applied index is now lower than readState.Index' (duration: 284.859µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:33:51.350017Z","caller":"traceutil/trace.go:171","msg":"trace[594562856] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"126.9107ms","start":"2026-08-14T04:33:51.223067Z","end":"2026-08-14T04:33:51.349977Z","steps":["trace[594562856] 'process raft request' (duration: 126.644781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:33:51.350342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.584768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-08-14T04:33:51.3504Z","caller":"traceutil/trace.go:171","msg":"trace[351872861] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1877; }","duration":"125.698951ms","start":"2026-08-14T04:33:51.224684Z","end":"2026-08-14T04:33:51.350383Z","steps":["trace[351872861] 'agreement among raft nodes before linearized reading' (duration: 125.34645ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:33:52.313768Z","caller":"traceutil/trace.go:171","msg":"trace[1684493434] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"109.940613ms","start":"2026-08-14T04:33:52.203789Z","end":"2026-08-14T04:33:52.31373Z","steps":["trace[1684493434] 'process raft request' (duration: 109.714886ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:34:42.520709Z","caller":"traceutil/trace.go:171","msg":"trace[625209289] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"168.79343ms","start":"2026-08-14T04:34:42.351874Z","end":"2026-08-14T04:34:42.520667Z","steps":["trace[625209289] 'process raft request' (duration: 168.375347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:34:50.567607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.865906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T04:34:50.567733Z","caller":"traceutil/trace.go:171","msg":"trace[1306578534] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2263; }","duration":"103.670476ms","start":"2026-08-14T04:34:50.464048Z","end":"2026-08-14T04:34:50.567718Z","steps":["trace[1306578534] 'read index received' (duration: 48.112µs)","trace[1306578534] 'applied index is now lower than readState.Index' (duration: 103.620714ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T04:34:50.567827Z","caller":"traceutil/trace.go:171","msg":"trace[981716818] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"160.550885ms","start":"2026-08-14T04:34:50.407244Z","end":"2026-08-14T04:34:50.567795Z","steps":["trace[981716818] 'process raft request' (duration: 54.41258ms)","trace[981716818] 'compare' (duration: 105.581407ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:34:50.567978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.896073ms","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-08-14T04:34:50.568094Z","caller":"traceutil/trace.go:171","msg":"trace[1555349208] 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:2173; }","duration":"104.075539ms","start":"2026-08-14T04:34:50.46399Z","end":"2026-08-14T04:34:50.568065Z","steps":["trace[1555349208] 'agreement among raft nodes before linearized reading' (duration: 103.839711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:34:51.072782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515506871803760,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T04:34:51.300214Z","caller":"traceutil/trace.go:171","msg":"trace[198477583] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"728.306422ms","start":"2026-08-14T04:34:50.571846Z","end":"2026-08-14T04:34:51.300153Z","steps":["trace[198477583] 'process raft request' (duration: 697.541663ms)","trace[198477583] 'compare' (duration: 30.232682ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:34:51.300637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:34:50.571833Z","time spent":"728.518149ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:34:51.323469Z","caller":"traceutil/trace.go:171","msg":"trace[405456842] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"751.315632ms","start":"2026-08-14T04:34:50.57211Z","end":"2026-08-14T04:34:51.323426Z","steps":["trace[405456842] 'process raft request' (duration: 751.043124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:34:51.323795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:34:50.572103Z","time spent":"751.497028ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:34:51.323892Z","caller":"traceutil/trace.go:171","msg":"trace[462639785] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"751.710455ms","start":"2026-08-14T04:34:50.572153Z","end":"2026-08-14T04:34:51.323863Z","steps":["trace[462639785] 'process raft request' (duration: 751.21618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:34:51.324091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:34:50.572148Z","time spent":"751.824639ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T04:34:51.324249Z","caller":"traceutil/trace.go:171","msg":"trace[1146745644] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"751.845519ms","start":"2026-08-14T04:34:50.572391Z","end":"2026-08-14T04:34:51.324237Z","steps":["trace[1146745644] 'process raft request' (duration: 751.318382ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T04:34:51.324266Z","caller":"traceutil/trace.go:171","msg":"trace[466292023] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2264; }","duration":"751.87597ms","start":"2026-08-14T04:34:50.572361Z","end":"2026-08-14T04:34:51.324237Z","steps":["trace[466292023] 'read index received' (duration: 696.987264ms)","trace[466292023] 'applied index is now lower than readState.Index' (duration: 54.887286ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T04:34:51.32435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:34:50.572373Z","time spent":"751.898272ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T04:34:51.324493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.109478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-14T04:34:51.324533Z","caller":"traceutil/trace.go:171","msg":"trace[546527198] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2178; }","duration":"752.188631ms","start":"2026-08-14T04:34:50.572332Z","end":"2026-08-14T04:34:51.324521Z","steps":["trace[546527198] 'agreement among raft nodes before linearized reading' (duration: 752.056116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:34:51.324605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.852883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-14T04:34:51.324647Z","caller":"traceutil/trace.go:171","msg":"trace[508850205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2178; }","duration":"748.907855ms","start":"2026-08-14T04:34:50.575726Z","end":"2026-08-14T04:34:51.324634Z","steps":["trace[508850205] 'agreement among raft nodes before linearized reading' (duration: 748.724129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:34:51.324673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:34:50.575719Z","time spent":"748.948136ms","remote":"127.0.0.1:52434","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-08-14T04:34:51.324568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:34:50.572318Z","time spent":"752.241552ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-08-14T04:34:51.325251Z","caller":"traceutil/trace.go:171","msg":"trace[2108678878] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"529.686581ms","start":"2026-08-14T04:34:50.79551Z","end":"2026-08-14T04:34:51.325196Z","steps":["trace[2108678878] 'process raft request' (duration: 528.589046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T04:34:51.32568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T04:34:50.79548Z","time spent":"529.906688ms","remote":"127.0.0.1:52434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}