{"level":"warn","ts":"2026-07-16T14:16:56.975788Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T14:16:56.975869Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-07-16T14:16:56.975934Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T14:16:56.975946Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-07-16T14:16:56.975962Z","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-07-16T14:16:56.976605Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-07-16T14:16:56.976678Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-07-16T14:16:56.981238Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.355142ms"} {"level":"info","ts":"2026-07-16T14:16:56.986589Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-07-16T14:16:56.986714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T14:16:56.98675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-07-16T14:16:56.986802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T14:16:56.986827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-07-16T14:16:56.986906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-07-16T14:16:57.000885Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T14:16:57.003611Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T14:16:57.005462Z","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-07-16T14:16:57.008667Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T14:16:57.008852Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T14:16:57.008924Z","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-07-16T14:16:57.009102Z","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-07-16T14:16:57.009125Z","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-07-16T14:16:57.011246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-07-16T14:16:57.011383Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-07-16T14:16:57.013456Z","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-07-16T14:16:57.013798Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T14:16:57.013847Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T14:16:57.014955Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-07-16T14:16:57.014998Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-07-16T14:16:57.288098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T14:16:57.290056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T14:16:57.290082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-07-16T14:16:57.290094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-07-16T14:16:57.2901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-07-16T14:16:57.290108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-07-16T14:16:57.290118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-07-16T14:16:57.291282Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T14:16:57.292266Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T14:16:57.292606Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T14:16:57.292834Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T14:16:57.292913Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T14:16:57.292633Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T14:16:57.294332Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T14:16:57.294393Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T14:16:57.294409Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T14:16:57.295188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-07-16T14:16:57.297166Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-16T14:16:59.604362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.155076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-07-16T14:16:59.604508Z","caller":"traceutil/trace.go:171","msg":"trace[383446221] linearizableReadLoop","detail":"{readStateIndex:71; appliedIndex:70; }","duration":"157.395765ms","start":"2026-07-16T14:16:59.447091Z","end":"2026-07-16T14:16:59.604487Z","steps":["trace[383446221] 'read index received' (duration: 9.405812ms)","trace[383446221] 'applied index is now lower than readState.Index' (duration: 147.986353ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:16:59.604566Z","caller":"traceutil/trace.go:171","msg":"trace[1836777298] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"186.666789ms","start":"2026-07-16T14:16:59.417884Z","end":"2026-07-16T14:16:59.604551Z","steps":["trace[1836777298] 'process raft request' (duration: 38.689427ms)","trace[1836777298] 'compare' (duration: 147.04693ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:16:59.604688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.558203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T14:16:59.604784Z","caller":"traceutil/trace.go:171","msg":"trace[865632896] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:67; }","duration":"157.71611ms","start":"2026-07-16T14:16:59.447047Z","end":"2026-07-16T14:16:59.604763Z","steps":["trace[865632896] 'agreement among raft nodes before linearized reading' (duration: 157.545162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:00.492624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.860359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-16T14:17:00.492968Z","caller":"traceutil/trace.go:171","msg":"trace[1443773976] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"189.603477ms","start":"2026-07-16T14:17:00.303308Z","end":"2026-07-16T14:17:00.492912Z","steps":["trace[1443773976] 'process raft request' (duration: 83.375531ms)","trace[1443773976] 'compare' (duration: 105.668301ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:00.493198Z","caller":"traceutil/trace.go:171","msg":"trace[58643539] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"189.153636ms","start":"2026-07-16T14:17:00.304021Z","end":"2026-07-16T14:17:00.493175Z","steps":["trace[58643539] 'process raft request' (duration: 188.743708ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:00.684312Z","caller":"traceutil/trace.go:171","msg":"trace[316600209] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"102.850055ms","start":"2026-07-16T14:17:00.581428Z","end":"2026-07-16T14:17:00.684278Z","steps":["trace[316600209] 'process raft request' (duration: 66.304023ms)","trace[316600209] 'compare' (duration: 36.300211ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:00.684548Z","caller":"traceutil/trace.go:171","msg":"trace[1379213650] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"102.412836ms","start":"2026-07-16T14:17:00.582008Z","end":"2026-07-16T14:17:00.684421Z","steps":["trace[1379213650] 'process raft request' (duration: 102.175725ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:00.816965Z","caller":"traceutil/trace.go:171","msg":"trace[772537578] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"127.05186ms","start":"2026-07-16T14:17:00.68986Z","end":"2026-07-16T14:17:00.816912Z","steps":["trace[772537578] 'process raft request' (duration: 107.992594ms)","trace[772537578] 'compare' (duration: 18.761903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:02.036794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11508399s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-16T14:17:02.03695Z","caller":"traceutil/trace.go:171","msg":"trace[602066320] linearizableReadLoop","detail":"{readStateIndex:98; appliedIndex:96; }","duration":"737.991954ms","start":"2026-07-16T14:17:01.298943Z","end":"2026-07-16T14:17:02.036934Z","steps":["trace[602066320] 'read index received' (duration: 447.329829ms)","trace[602066320] 'applied index is now lower than readState.Index' (duration: 290.661245ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:02.03709Z","caller":"traceutil/trace.go:171","msg":"trace[1848292822] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"1.213821703s","start":"2026-07-16T14:17:00.823202Z","end":"2026-07-16T14:17:02.037024Z","steps":["trace[1848292822] 'process raft request' (duration: 98.456161ms)","trace[1848292822] 'compare' (duration: 1.114961514s)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:02.037329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:00.82318Z","time spent":"1.214046223s","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T14:17:02.037449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.496896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-16T14:17:02.037437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.012446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T14:17:02.03718Z","caller":"traceutil/trace.go:171","msg":"trace[1977275888] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"1.213186334s","start":"2026-07-16T14:17:00.823873Z","end":"2026-07-16T14:17:02.03706Z","steps":["trace[1977275888] 'process raft request' (duration: 1.213008827s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:02.037507Z","caller":"traceutil/trace.go:171","msg":"trace[1940790888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:93; }","duration":"636.120491ms","start":"2026-07-16T14:17:01.401368Z","end":"2026-07-16T14:17:02.037488Z","steps":["trace[1940790888] 'agreement among raft nodes before linearized reading' (duration: 635.929123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:02.037549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:01.401354Z","time spent":"636.185914ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T14:17:02.037492Z","caller":"traceutil/trace.go:171","msg":"trace[964840540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:93; }","duration":"738.537818ms","start":"2026-07-16T14:17:01.298938Z","end":"2026-07-16T14:17:02.037476Z","steps":["trace[964840540] 'agreement among raft nodes before linearized reading' (duration: 738.088158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:02.037651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:00.823859Z","time spent":"1.213685906s","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:02.037783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:01.298886Z","time spent":"738.822741ms","remote":"127.0.0.1:39018","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-07-16T14:17:04.907372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.213164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-16T14:17:04.907548Z","caller":"traceutil/trace.go:171","msg":"trace[804235225] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:308; }","duration":"119.399882ms","start":"2026-07-16T14:17:04.788084Z","end":"2026-07-16T14:17:04.907484Z","steps":["trace[804235225] 'range keys from in-memory index tree' (duration: 119.083339ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:05.343868Z","caller":"traceutil/trace.go:171","msg":"trace[968997203] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"526.769002ms","start":"2026-07-16T14:17:04.81708Z","end":"2026-07-16T14:17:05.343849Z","steps":["trace[968997203] 'process raft request' (duration: 526.636167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:05.344021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:04.817052Z","time spent":"526.892678ms","remote":"127.0.0.1:39196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T14:17:05.345062Z","caller":"traceutil/trace.go:171","msg":"trace[818014942] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"432.901875ms","start":"2026-07-16T14:17:04.912145Z","end":"2026-07-16T14:17:05.345047Z","steps":["trace[818014942] 'process raft request' (duration: 432.830682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:05.345263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:04.912107Z","time spent":"433.122233ms","remote":"127.0.0.1:39196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T14:17:05.547078Z","caller":"traceutil/trace.go:171","msg":"trace[465848541] transaction","detail":"{read_only:false; number_of_response:0; response_revision:313; }","duration":"109.575925ms","start":"2026-07-16T14:17:05.437473Z","end":"2026-07-16T14:17:05.547049Z","steps":["trace[465848541] 'process raft request' (duration: 105.867349ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:05.585868Z","caller":"traceutil/trace.go:171","msg":"trace[328667562] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"148.01395ms","start":"2026-07-16T14:17:05.437818Z","end":"2026-07-16T14:17:05.585832Z","steps":["trace[328667562] 'process raft request' (duration: 147.78626ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:05.585933Z","caller":"traceutil/trace.go:171","msg":"trace[1504261215] transaction","detail":"{read_only:false; number_of_response:0; response_revision:314; }","duration":"146.811309ms","start":"2026-07-16T14:17:05.439106Z","end":"2026-07-16T14:17:05.585917Z","steps":["trace[1504261215] 'process raft request' (duration: 146.675764ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:05.585916Z","caller":"traceutil/trace.go:171","msg":"trace[1179409049] transaction","detail":"{read_only:false; number_of_response:0; response_revision:314; }","duration":"147.989489ms","start":"2026-07-16T14:17:05.4379Z","end":"2026-07-16T14:17:05.58589Z","steps":["trace[1179409049] 'process raft request' (duration: 147.845433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:05.586142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:05.586098Z","caller":"traceutil/trace.go:171","msg":"trace[1640116457] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:321; }","duration":"147.582872ms","start":"2026-07-16T14:17:05.438352Z","end":"2026-07-16T14:17:05.585935Z","steps":["trace[1640116457] 'read index received' (duration: 105.033904ms)","trace[1640116457] 'applied index is now lower than readState.Index' (duration: 42.547748ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:05.586203Z","caller":"traceutil/trace.go:171","msg":"trace[1268403439] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:315; }","duration":"147.858704ms","start":"2026-07-16T14:17:05.438327Z","end":"2026-07-16T14:17:05.586185Z","steps":["trace[1268403439] 'agreement among raft nodes before linearized reading' (duration: 147.735458ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:05.586318Z","caller":"traceutil/trace.go:171","msg":"trace[482837544] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"119.767183ms","start":"2026-07-16T14:17:05.466197Z","end":"2026-07-16T14:17:05.585964Z","steps":["trace[482837544] 'process raft request' (duration: 119.610357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:11.056644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317253889531507,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T14:17:11.213343Z","caller":"traceutil/trace.go:171","msg":"trace[180783646] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"658.041269ms","start":"2026-07-16T14:17:10.55528Z","end":"2026-07-16T14:17:11.213321Z","steps":["trace[180783646] 'process raft request' (duration: 657.913544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:11.213478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:10.555258Z","time spent":"658.152203ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:11.258958Z","caller":"traceutil/trace.go:171","msg":"trace[1940837433] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"702.899407ms","start":"2026-07-16T14:17:10.55604Z","end":"2026-07-16T14:17:11.25894Z","steps":["trace[1940837433] 'read index received' (duration: 657.705396ms)","trace[1940837433] 'applied index is now lower than readState.Index' (duration: 45.193171ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:11.25905Z","caller":"traceutil/trace.go:171","msg":"trace[354754804] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"701.358305ms","start":"2026-07-16T14:17:10.557685Z","end":"2026-07-16T14:17:11.259043Z","steps":["trace[354754804] 'process raft request' (duration: 701.156567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:11.259183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:10.557667Z","time spent":"701.428869ms","remote":"127.0.0.1:39382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:11.259219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.917608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:11.259263Z","caller":"traceutil/trace.go:171","msg":"trace[543521653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"698.008512ms","start":"2026-07-16T14:17:10.561242Z","end":"2026-07-16T14:17:11.259251Z","steps":["trace[543521653] 'agreement among raft nodes before linearized reading' (duration: 697.904598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:11.259296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:10.561236Z","time spent":"698.052563ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:17:11.259298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.384601ms","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-07-16T14:17:11.259367Z","caller":"traceutil/trace.go:171","msg":"trace[888276147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"483.496035ms","start":"2026-07-16T14:17:10.775851Z","end":"2026-07-16T14:17:11.259348Z","steps":["trace[888276147] 'agreement among raft nodes before linearized reading' (duration: 483.384861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:11.259373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.329554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"warn","ts":"2026-07-16T14:17:11.259405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:10.775828Z","time spent":"483.569308ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T14:17:11.259458Z","caller":"traceutil/trace.go:171","msg":"trace[1294989956] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:396; }","duration":"703.424278ms","start":"2026-07-16T14:17:10.556011Z","end":"2026-07-16T14:17:11.259435Z","steps":["trace[1294989956] 'agreement among raft nodes before linearized reading' (duration: 703.22333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:11.259562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:10.55549Z","time spent":"704.059543ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7318,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-07-16T14:17:11.947466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317253889531511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T14:17:13.125743Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.124263951s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T14:17:13.133176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138793227s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:13.133399Z","caller":"traceutil/trace.go:171","msg":"trace[266267153] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"1.869645019s","start":"2026-07-16T14:17:11.263729Z","end":"2026-07-16T14:17:13.133374Z","steps":["trace[266267153] 'process raft request' (duration: 730.567821ms)","trace[266267153] 'compare' (duration: 1.138635601s)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:13.13346Z","caller":"traceutil/trace.go:171","msg":"trace[1074229596] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:408; }","duration":"1.686694097s","start":"2026-07-16T14:17:11.446752Z","end":"2026-07-16T14:17:13.133446Z","steps":["trace[1074229596] 'read index received' (duration: 547.560326ms)","trace[1074229596] 'applied index is now lower than readState.Index' (duration: 1.139132941s)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:13.133548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:11.263712Z","time spent":"1.869746153s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:13.1338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569870871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:13.133842Z","caller":"traceutil/trace.go:171","msg":"trace[1518109824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:398; }","duration":"1.569954654s","start":"2026-07-16T14:17:11.563875Z","end":"2026-07-16T14:17:13.133829Z","steps":["trace[1518109824] 'agreement among raft nodes before linearized reading' (duration: 1.569888861s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:13.133844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.687063631s","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-07-16T14:17:13.133884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.356971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-16T14:17:13.133927Z","caller":"traceutil/trace.go:171","msg":"trace[1459441353] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:398; }","duration":"334.448374ms","start":"2026-07-16T14:17:12.799467Z","end":"2026-07-16T14:17:13.133915Z","steps":["trace[1459441353] 'agreement among raft nodes before linearized reading' (duration: 334.353831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:13.133928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.979873ms","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-07-16T14:17:13.133927Z","caller":"traceutil/trace.go:171","msg":"trace[1434129337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:398; }","duration":"1.687192326s","start":"2026-07-16T14:17:11.446712Z","end":"2026-07-16T14:17:13.133905Z","steps":["trace[1434129337] 'agreement among raft nodes before linearized reading' (duration: 1.687007559s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:13.133962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:12.799446Z","time spent":"334.508857ms","remote":"127.0.0.1:39032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"info","ts":"2026-07-16T14:17:13.133963Z","caller":"traceutil/trace.go:171","msg":"trace[64973958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:398; }","duration":"984.041036ms","start":"2026-07-16T14:17:12.149912Z","end":"2026-07-16T14:17:13.133953Z","steps":["trace[64973958] 'agreement among raft nodes before linearized reading' (duration: 983.984503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:13.133926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.2659ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T14:17:13.133991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:12.149895Z","time spent":"984.089658ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T14:17:13.134008Z","caller":"traceutil/trace.go:171","msg":"trace[67636114] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:398; }","duration":"504.361073ms","start":"2026-07-16T14:17:12.629636Z","end":"2026-07-16T14:17:13.133997Z","steps":["trace[67636114] 'agreement among raft nodes before linearized reading' (duration: 504.246929ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:13.134009Z","caller":"traceutil/trace.go:171","msg":"trace[675417821] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"1.855549552s","start":"2026-07-16T14:17:11.278447Z","end":"2026-07-16T14:17:13.133996Z","steps":["trace[675417821] 'process raft request' (duration: 1.854913178s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:13.134074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:11.278432Z","time spent":"1.855602875s","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:13.13397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:11.446696Z","time spent":"1.687265489s","remote":"127.0.0.1:39278","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-07-16T14:17:13.133875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:11.563855Z","time spent":"1.570013096s","remote":"127.0.0.1:39018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:17:13.70967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.549025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:13.709896Z","caller":"traceutil/trace.go:171","msg":"trace[1553144705] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:411; }","duration":"518.714185ms","start":"2026-07-16T14:17:13.191165Z","end":"2026-07-16T14:17:13.709879Z","steps":["trace[1553144705] 'read index received' (duration: 139.916371ms)","trace[1553144705] 'applied index is now lower than readState.Index' (duration: 378.797044ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:13.709989Z","caller":"traceutil/trace.go:171","msg":"trace[958569901] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"571.273945ms","start":"2026-07-16T14:17:13.138672Z","end":"2026-07-16T14:17:13.709946Z","steps":["trace[958569901] 'process raft request' (duration: 571.091658ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:13.710051Z","caller":"traceutil/trace.go:171","msg":"trace[1836902626] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"571.460282ms","start":"2026-07-16T14:17:13.13857Z","end":"2026-07-16T14:17:13.71003Z","steps":["trace[1836902626] 'process raft request' (duration: 192.46819ms)","trace[1836902626] 'compare' (duration: 378.268214ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:13.710133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:13.138663Z","time spent":"571.39133ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:13.710138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:13.138555Z","time spent":"571.536645ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:13.710279Z","caller":"traceutil/trace.go:171","msg":"trace[17800743] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"517.959486ms","start":"2026-07-16T14:17:13.192309Z","end":"2026-07-16T14:17:13.710269Z","steps":["trace[17800743] 'process raft request' (duration: 517.53808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:13.710275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.923964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T14:17:13.710337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:13.192293Z","time spent":"518.011088ms","remote":"127.0.0.1:39032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:13.710339Z","caller":"traceutil/trace.go:171","msg":"trace[644941289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"511.040339ms","start":"2026-07-16T14:17:13.199282Z","end":"2026-07-16T14:17:13.710323Z","steps":["trace[644941289] 'agreement among raft nodes before linearized reading' (duration: 510.901974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:13.710378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:13.199262Z","time spent":"511.108882ms","remote":"127.0.0.1:39018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:17:13.710382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.371277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-07-16T14:17:13.71044Z","caller":"traceutil/trace.go:171","msg":"trace[218413125] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:401; }","duration":"572.463051ms","start":"2026-07-16T14:17:13.137961Z","end":"2026-07-16T14:17:13.710424Z","steps":["trace[218413125] 'agreement among raft nodes before linearized reading' (duration: 571.999833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:13.710485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:13.137943Z","time spent":"572.530673ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7318,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-07-16T14:17:13.710562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.552675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T14:17:13.710639Z","caller":"traceutil/trace.go:171","msg":"trace[1943914513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:401; }","duration":"492.66513ms","start":"2026-07-16T14:17:13.217953Z","end":"2026-07-16T14:17:13.710618Z","steps":["trace[1943914513] 'agreement among raft nodes before linearized reading' (duration: 492.466702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:13.710681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:13.217942Z","time spent":"492.730443ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T14:17:14.064684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.485355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:14.064905Z","caller":"traceutil/trace.go:171","msg":"trace[1057585842] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"343.546219ms","start":"2026-07-16T14:17:13.721338Z","end":"2026-07-16T14:17:14.064884Z","steps":["trace[1057585842] 'process raft request' (duration: 343.447765ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:14.064941Z","caller":"traceutil/trace.go:171","msg":"trace[943863944] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"345.648571ms","start":"2026-07-16T14:17:13.719281Z","end":"2026-07-16T14:17:14.06493Z","steps":["trace[943863944] 'read index received' (duration: 129.850783ms)","trace[943863944] 'applied index is now lower than readState.Index' (duration: 215.796088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:14.064984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:13.721326Z","time spent":"343.626512ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:14.065018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.741064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-16T14:17:14.06504Z","caller":"traceutil/trace.go:171","msg":"trace[461254356] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:403; }","duration":"345.795787ms","start":"2026-07-16T14:17:13.719238Z","end":"2026-07-16T14:17:14.065033Z","steps":["trace[461254356] 'agreement among raft nodes before linearized reading' (duration: 345.724604ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:14.064929Z","caller":"traceutil/trace.go:171","msg":"trace[1873247030] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"346.321916ms","start":"2026-07-16T14:17:13.718573Z","end":"2026-07-16T14:17:14.064895Z","steps":["trace[1873247030] 'process raft request' (duration: 130.54886ms)","trace[1873247030] 'compare' (duration: 215.159393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:14.065057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:13.719216Z","time spent":"345.837807ms","remote":"127.0.0.1:39282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-07-16T14:17:14.06521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:13.718558Z","time spent":"346.521054ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:14.818272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.118455ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:14.818435Z","caller":"traceutil/trace.go:171","msg":"trace[1128650159] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:403; }","duration":"603.291332ms","start":"2026-07-16T14:17:14.215129Z","end":"2026-07-16T14:17:14.81842Z","steps":["trace[1128650159] 'range keys from in-memory index tree' (duration: 603.103405ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:14.818611Z","caller":"traceutil/trace.go:171","msg":"trace[582266958] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"744.148504ms","start":"2026-07-16T14:17:14.074453Z","end":"2026-07-16T14:17:14.818601Z","steps":["trace[582266958] 'process raft request' (duration: 739.193055ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:14.818649Z","caller":"traceutil/trace.go:171","msg":"trace[1738737588] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"245.74893ms","start":"2026-07-16T14:17:14.57289Z","end":"2026-07-16T14:17:14.818639Z","steps":["trace[1738737588] 'process raft request' (duration: 245.703938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:14.81869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:14.074435Z","time spent":"744.203286ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:14.818701Z","caller":"traceutil/trace.go:171","msg":"trace[978756808] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"418.631656ms","start":"2026-07-16T14:17:14.400062Z","end":"2026-07-16T14:17:14.818693Z","steps":["trace[978756808] 'read index received' (duration: 413.590153ms)","trace[978756808] 'applied index is now lower than readState.Index' (duration: 5.040773ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:14.818768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.711289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:14.818788Z","caller":"traceutil/trace.go:171","msg":"trace[606041468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"418.765951ms","start":"2026-07-16T14:17:14.400017Z","end":"2026-07-16T14:17:14.818783Z","steps":["trace[606041468] 'agreement among raft nodes before linearized reading' (duration: 418.71832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:14.818789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.783223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T14:17:14.818802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:14.399998Z","time spent":"418.800992ms","remote":"127.0.0.1:39018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T14:17:14.818813Z","caller":"traceutil/trace.go:171","msg":"trace[189882999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"255.831415ms","start":"2026-07-16T14:17:14.562974Z","end":"2026-07-16T14:17:14.818805Z","steps":["trace[189882999] 'agreement among raft nodes before linearized reading' (duration: 255.794664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:24.484812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.836909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:24.484898Z","caller":"traceutil/trace.go:171","msg":"trace[844949219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"101.969544ms","start":"2026-07-16T14:17:24.382906Z","end":"2026-07-16T14:17:24.484876Z","steps":["trace[844949219] 'range keys from in-memory index tree' (duration: 101.745446ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:26.004881Z","caller":"traceutil/trace.go:171","msg":"trace[576329413] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:451; }","duration":"140.596852ms","start":"2026-07-16T14:17:25.86426Z","end":"2026-07-16T14:17:26.004856Z","steps":["trace[576329413] 'read index received' (duration: 139.578217ms)","trace[576329413] 'applied index is now lower than readState.Index' (duration: 1.017585ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:26.005095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.82542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-07-16T14:17:26.005308Z","caller":"traceutil/trace.go:171","msg":"trace[1743962491] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:437; }","duration":"141.089668ms","start":"2026-07-16T14:17:25.864204Z","end":"2026-07-16T14:17:26.005293Z","steps":["trace[1743962491] 'agreement among raft nodes before linearized reading' (duration: 140.8257ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:26.005184Z","caller":"traceutil/trace.go:171","msg":"trace[1492427547] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"154.205998ms","start":"2026-07-16T14:17:25.850952Z","end":"2026-07-16T14:17:26.005158Z","steps":["trace[1492427547] 'process raft request' (duration: 152.879743ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:26.243928Z","caller":"traceutil/trace.go:171","msg":"trace[773394773] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"109.885604ms","start":"2026-07-16T14:17:26.134022Z","end":"2026-07-16T14:17:26.243908Z","steps":["trace[773394773] 'read index received' (duration: 18.459825ms)","trace[773394773] 'applied index is now lower than readState.Index' (duration: 91.425019ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:26.244044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.002698ms","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-07-16T14:17:26.244069Z","caller":"traceutil/trace.go:171","msg":"trace[303014172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:439; }","duration":"110.080462ms","start":"2026-07-16T14:17:26.13398Z","end":"2026-07-16T14:17:26.24406Z","steps":["trace[303014172] 'agreement among raft nodes before linearized reading' (duration: 109.996919ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:26.24406Z","caller":"traceutil/trace.go:171","msg":"trace[1647987762] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"176.049927ms","start":"2026-07-16T14:17:26.067971Z","end":"2026-07-16T14:17:26.244021Z","steps":["trace[1647987762] 'process raft request' (duration: 84.522565ms)","trace[1647987762] 'compare' (duration: 91.307165ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:26.476208Z","caller":"traceutil/trace.go:171","msg":"trace[1625441198] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"192.210195ms","start":"2026-07-16T14:17:26.283968Z","end":"2026-07-16T14:17:26.476178Z","steps":["trace[1625441198] 'process raft request' (duration: 192.131183ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:26.476236Z","caller":"traceutil/trace.go:171","msg":"trace[1373904387] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"192.520685ms","start":"2026-07-16T14:17:26.28369Z","end":"2026-07-16T14:17:26.476211Z","steps":["trace[1373904387] 'process raft request' (duration: 135.863685ms)","trace[1373904387] 'compare' (duration: 56.384681ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:26.477161Z","caller":"traceutil/trace.go:171","msg":"trace[549578962] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"190.842019ms","start":"2026-07-16T14:17:26.286303Z","end":"2026-07-16T14:17:26.477145Z","steps":["trace[549578962] 'process raft request' (duration: 190.650863ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:26.477608Z","caller":"traceutil/trace.go:171","msg":"trace[205650975] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"188.487349ms","start":"2026-07-16T14:17:26.2891Z","end":"2026-07-16T14:17:26.477587Z","steps":["trace[205650975] 'process raft request' (duration: 188.293773ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:26.477654Z","caller":"traceutil/trace.go:171","msg":"trace[1817575457] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"191.014574ms","start":"2026-07-16T14:17:26.286577Z","end":"2026-07-16T14:17:26.477592Z","steps":["trace[1817575457] 'process raft request' (duration: 190.499817ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:28.681354Z","caller":"traceutil/trace.go:171","msg":"trace[2060931329] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"118.284483ms","start":"2026-07-16T14:17:28.563043Z","end":"2026-07-16T14:17:28.681327Z","steps":["trace[2060931329] 'read index received' (duration: 83.91395ms)","trace[2060931329] 'applied index is now lower than readState.Index' (duration: 34.369043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:28.681559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.48042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:28.681696Z","caller":"traceutil/trace.go:171","msg":"trace[875587290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"118.680107ms","start":"2026-07-16T14:17:28.562984Z","end":"2026-07-16T14:17:28.681664Z","steps":["trace[875587290] 'agreement among raft nodes before linearized reading' (duration: 118.427428ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:29.977598Z","caller":"traceutil/trace.go:171","msg":"trace[475258624] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"113.110645ms","start":"2026-07-16T14:17:29.864463Z","end":"2026-07-16T14:17:29.977574Z","steps":["trace[475258624] 'process raft request' (duration: 112.902659ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:39.694769Z","caller":"traceutil/trace.go:171","msg":"trace[1082335130] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"253.227241ms","start":"2026-07-16T14:17:39.441515Z","end":"2026-07-16T14:17:39.694742Z","steps":["trace[1082335130] 'process raft request' (duration: 247.319493ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:39.694924Z","caller":"traceutil/trace.go:171","msg":"trace[274113321] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"250.215751ms","start":"2026-07-16T14:17:39.444689Z","end":"2026-07-16T14:17:39.694905Z","steps":["trace[274113321] 'process raft request' (duration: 250.074317ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:39.694952Z","caller":"traceutil/trace.go:171","msg":"trace[1458564726] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"250.349175ms","start":"2026-07-16T14:17:39.444574Z","end":"2026-07-16T14:17:39.694923Z","steps":["trace[1458564726] 'process raft request' (duration: 250.097617ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:39.695168Z","caller":"traceutil/trace.go:171","msg":"trace[41621783] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"244.2046ms","start":"2026-07-16T14:17:39.450953Z","end":"2026-07-16T14:17:39.695158Z","steps":["trace[41621783] 'process raft request' (duration: 243.912961ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:39.695298Z","caller":"traceutil/trace.go:171","msg":"trace[1016690174] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:574; }","duration":"132.702419ms","start":"2026-07-16T14:17:39.562582Z","end":"2026-07-16T14:17:39.695284Z","steps":["trace[1016690174] 'read index received' (duration: 126.386958ms)","trace[1016690174] 'applied index is now lower than readState.Index' (duration: 6.314751ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:39.695351Z","caller":"traceutil/trace.go:171","msg":"trace[1906839018] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"241.738266ms","start":"2026-07-16T14:17:39.453601Z","end":"2026-07-16T14:17:39.695339Z","steps":["trace[1906839018] 'process raft request' (duration: 241.508159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:39.695397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.803041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:39.695434Z","caller":"traceutil/trace.go:171","msg":"trace[791313397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"132.878514ms","start":"2026-07-16T14:17:39.562545Z","end":"2026-07-16T14:17:39.695424Z","steps":["trace[791313397] 'agreement among raft nodes before linearized reading' (duration: 132.796221ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:39.695474Z","caller":"traceutil/trace.go:171","msg":"trace[185455204] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"161.999139ms","start":"2026-07-16T14:17:39.533467Z","end":"2026-07-16T14:17:39.695466Z","steps":["trace[185455204] 'process raft request' (duration: 161.748742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:40.623361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317253889531827,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T14:17:41.124183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317253889531827,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T14:17:41.127005Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.420605659s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T14:17:41.181324Z","caller":"traceutil/trace.go:171","msg":"trace[1639044633] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.474967014s","start":"2026-07-16T14:17:39.706297Z","end":"2026-07-16T14:17:41.181264Z","steps":["trace[1639044633] 'process raft request' (duration: 1.420833676s)","trace[1639044633] 'compare' (duration: 53.639934ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:41.181774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:39.706281Z","time spent":"1.47517615s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:41.193081Z","caller":"traceutil/trace.go:171","msg":"trace[1867945608] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.484479983s","start":"2026-07-16T14:17:39.708571Z","end":"2026-07-16T14:17:41.193051Z","steps":["trace[1867945608] 'process raft request' (duration: 1.484180444s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:41.193199Z","caller":"traceutil/trace.go:171","msg":"trace[308704683] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.457173603s","start":"2026-07-16T14:17:39.736006Z","end":"2026-07-16T14:17:41.193179Z","steps":["trace[308704683] 'process raft request' (duration: 1.456904865s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:41.193264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:39.708553Z","time spent":"1.484611107s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:41.193296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:39.735986Z","time spent":"1.457260945s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:41.193616Z","caller":"traceutil/trace.go:171","msg":"trace[419150496] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.431294324s","start":"2026-07-16T14:17:39.76231Z","end":"2026-07-16T14:17:41.193605Z","steps":["trace[419150496] 'process raft request' (duration: 1.430710897s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:41.193682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:39.762279Z","time spent":"1.431377327s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T14:17:41.194454Z","caller":"traceutil/trace.go:171","msg":"trace[1643341442] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:580; }","duration":"1.071390829s","start":"2026-07-16T14:17:40.123042Z","end":"2026-07-16T14:17:41.194433Z","steps":["trace[1643341442] 'read index received' (duration: 1.004269377s)","trace[1643341442] 'applied index is now lower than readState.Index' (duration: 67.120472ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:41.194557Z","caller":"traceutil/trace.go:171","msg":"trace[1253174518] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.408783107s","start":"2026-07-16T14:17:39.785765Z","end":"2026-07-16T14:17:41.194548Z","steps":["trace[1253174518] 'process raft request' (duration: 1.408404816s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:41.19463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:39.78574Z","time spent":"1.408845859s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:41.194901Z","caller":"traceutil/trace.go:171","msg":"trace[309009827] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"949.992962ms","start":"2026-07-16T14:17:40.244898Z","end":"2026-07-16T14:17:41.194891Z","steps":["trace[309009827] 'process raft request' (duration: 949.477917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:41.194963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:40.244875Z","time spent":"950.057884ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:41.19519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.473858ms","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-07-16T14:17:41.19523Z","caller":"traceutil/trace.go:171","msg":"trace[1424438715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"989.54184ms","start":"2026-07-16T14:17:40.205678Z","end":"2026-07-16T14:17:41.19522Z","steps":["trace[1424438715] 'agreement among raft nodes before linearized reading' (duration: 989.416966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:41.195207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072140101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-16T14:17:41.195294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:40.205663Z","time spent":"989.590511ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T14:17:41.195309Z","caller":"traceutil/trace.go:171","msg":"trace[764162519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"1.072271195s","start":"2026-07-16T14:17:40.123009Z","end":"2026-07-16T14:17:41.19528Z","steps":["trace[764162519] 'agreement among raft nodes before linearized reading' (duration: 1.072011667s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:41.195354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:40.122994Z","time spent":"1.072350737s","remote":"127.0.0.1:39278","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-07-16T14:17:41.195463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.244167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T14:17:41.195497Z","caller":"traceutil/trace.go:171","msg":"trace[1990230159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"772.309429ms","start":"2026-07-16T14:17:40.423179Z","end":"2026-07-16T14:17:41.195488Z","steps":["trace[1990230159] 'agreement among raft nodes before linearized reading' (duration: 772.224717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:41.195556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:40.423147Z","time spent":"772.402681ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T14:17:42.626082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.732093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:42.626199Z","caller":"traceutil/trace.go:171","msg":"trace[1848068060] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"642.253768ms","start":"2026-07-16T14:17:41.983927Z","end":"2026-07-16T14:17:42.626181Z","steps":["trace[1848068060] 'process raft request' (duration: 160.35971ms)","trace[1848068060] 'compare' (duration: 481.540627ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:42.626273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:41.983908Z","time spent":"642.342671ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T14:17:42.701411Z","caller":"traceutil/trace.go:171","msg":"trace[1239938059] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"136.924599ms","start":"2026-07-16T14:17:42.564465Z","end":"2026-07-16T14:17:42.70139Z","steps":["trace[1239938059] 'read index received' (duration: 135.424615ms)","trace[1239938059] 'applied index is now lower than readState.Index' (duration: 1.499184ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:42.701542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.063672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:42.701568Z","caller":"traceutil/trace.go:171","msg":"trace[2000415296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"137.140505ms","start":"2026-07-16T14:17:42.564419Z","end":"2026-07-16T14:17:42.70156Z","steps":["trace[2000415296] 'agreement among raft nodes before linearized reading' (duration: 137.054932ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:42.703035Z","caller":"traceutil/trace.go:171","msg":"trace[1430620537] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"620.249799ms","start":"2026-07-16T14:17:42.082773Z","end":"2026-07-16T14:17:42.703023Z","steps":["trace[1430620537] 'process raft request' (duration: 617.878479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:42.703124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:42.08274Z","time spent":"620.326571ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:43.143742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.977659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:43.143904Z","caller":"traceutil/trace.go:171","msg":"trace[728430225] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"203.964106ms","start":"2026-07-16T14:17:42.939919Z","end":"2026-07-16T14:17:43.143883Z","steps":["trace[728430225] 'read index received' (duration: 3.631476ms)","trace[728430225] 'applied index is now lower than readState.Index' (duration: 200.33091ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:43.143996Z","caller":"traceutil/trace.go:171","msg":"trace[1296220053] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"206.744328ms","start":"2026-07-16T14:17:42.93718Z","end":"2026-07-16T14:17:43.143925Z","steps":["trace[1296220053] 'compare' (duration: 199.718402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:43.144175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.227204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-07-16T14:17:43.144259Z","caller":"traceutil/trace.go:171","msg":"trace[48581781] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:608; }","duration":"204.365058ms","start":"2026-07-16T14:17:42.939874Z","end":"2026-07-16T14:17:43.144239Z","steps":["trace[48581781] 'agreement among raft nodes before linearized reading' (duration: 204.079ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:43.204285Z","caller":"traceutil/trace.go:171","msg":"trace[1698031145] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"218.705012ms","start":"2026-07-16T14:17:42.98555Z","end":"2026-07-16T14:17:43.204255Z","steps":["trace[1698031145] 'process raft request' (duration: 218.2836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:43.517533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.192098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:43.517631Z","caller":"traceutil/trace.go:171","msg":"trace[1806461333] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"371.06406ms","start":"2026-07-16T14:17:43.14655Z","end":"2026-07-16T14:17:43.517614Z","steps":["trace[1806461333] 'read index received' (duration: 57.157209ms)","trace[1806461333] 'applied index is now lower than readState.Index' (duration: 313.905821ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:43.517698Z","caller":"traceutil/trace.go:171","msg":"trace[521485843] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"513.652928ms","start":"2026-07-16T14:17:43.004035Z","end":"2026-07-16T14:17:43.517687Z","steps":["trace[521485843] 'process raft request' (duration: 351.189603ms)","trace[521485843] 'compare' (duration: 161.93489ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:43.517751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:43.004014Z","time spent":"513.70876ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:43.51788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.345988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-16T14:17:43.517906Z","caller":"traceutil/trace.go:171","msg":"trace[2045367963] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:610; }","duration":"371.41908ms","start":"2026-07-16T14:17:43.146477Z","end":"2026-07-16T14:17:43.517896Z","steps":["trace[2045367963] 'agreement among raft nodes before linearized reading' (duration: 371.365368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:43.517932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:43.146441Z","time spent":"371.485302ms","remote":"127.0.0.1:39156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-16T14:17:43.523942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.030862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"warn","ts":"2026-07-16T14:17:43.523943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.037093ms","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-07-16T14:17:43.523974Z","caller":"traceutil/trace.go:171","msg":"trace[1080275698] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:611; }","duration":"368.067383ms","start":"2026-07-16T14:17:43.155899Z","end":"2026-07-16T14:17:43.523966Z","steps":["trace[1080275698] 'agreement among raft nodes before linearized reading' (duration: 367.95487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:43.523955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.567553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-16T14:17:43.523998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:43.15584Z","time spent":"368.153065ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10894,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-16T14:17:43.524018Z","caller":"traceutil/trace.go:171","msg":"trace[1241280870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"301.641155ms","start":"2026-07-16T14:17:43.222362Z","end":"2026-07-16T14:17:43.524003Z","steps":["trace[1241280870] 'agreement among raft nodes before linearized reading' (duration: 301.46832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:43.524045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:43.222326Z","time spent":"301.712667ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T14:17:43.524097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.819321ms","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-07-16T14:17:43.524115Z","caller":"traceutil/trace.go:171","msg":"trace[687750785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"310.863902ms","start":"2026-07-16T14:17:43.213245Z","end":"2026-07-16T14:17:43.524108Z","steps":["trace[687750785] 'agreement among raft nodes before linearized reading' (duration: 310.806741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:43.524131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:43.213229Z","time spent":"310.898264ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T14:17:43.524128Z","caller":"traceutil/trace.go:171","msg":"trace[1467542161] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"268.608346ms","start":"2026-07-16T14:17:43.255496Z","end":"2026-07-16T14:17:43.524104Z","steps":["trace[1467542161] 'process raft request' (duration: 268.199854ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:43.52398Z","caller":"traceutil/trace.go:171","msg":"trace[1989615649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"305.089205ms","start":"2026-07-16T14:17:43.218882Z","end":"2026-07-16T14:17:43.523971Z","steps":["trace[1989615649] 'agreement among raft nodes before linearized reading' (duration: 304.983682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:43.524269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:43.218873Z","time spent":"305.389714ms","remote":"127.0.0.1:39278","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":"info","ts":"2026-07-16T14:17:43.739387Z","caller":"traceutil/trace.go:171","msg":"trace[1694947398] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:635; }","duration":"187.161452ms","start":"2026-07-16T14:17:43.552101Z","end":"2026-07-16T14:17:43.739262Z","steps":["trace[1694947398] 'read index received' (duration: 28.446705ms)","trace[1694947398] 'applied index is now lower than readState.Index' (duration: 158.713597ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:43.739471Z","caller":"traceutil/trace.go:171","msg":"trace[1137548859] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"207.466731ms","start":"2026-07-16T14:17:43.531974Z","end":"2026-07-16T14:17:43.739441Z","steps":["trace[1137548859] 'process raft request' (duration: 116.4677ms)","trace[1137548859] 'compare' (duration: 90.337252ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:43.739568Z","caller":"traceutil/trace.go:171","msg":"trace[491347088] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"207.467262ms","start":"2026-07-16T14:17:43.532087Z","end":"2026-07-16T14:17:43.739555Z","steps":["trace[491347088] 'process raft request' (duration: 207.007848ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:43.739678Z","caller":"traceutil/trace.go:171","msg":"trace[651563769] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"207.510663ms","start":"2026-07-16T14:17:43.532148Z","end":"2026-07-16T14:17:43.739659Z","steps":["trace[651563769] 'process raft request' (duration: 207.06592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:43.739862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.739349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-07-16T14:17:43.739948Z","caller":"traceutil/trace.go:171","msg":"trace[1447481626] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:615; }","duration":"187.840962ms","start":"2026-07-16T14:17:43.552086Z","end":"2026-07-16T14:17:43.739927Z","steps":["trace[1447481626] 'agreement among raft nodes before linearized reading' (duration: 187.569164ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:43.931984Z","caller":"traceutil/trace.go:171","msg":"trace[1682332309] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"192.484707ms","start":"2026-07-16T14:17:43.739481Z","end":"2026-07-16T14:17:43.931966Z","steps":["trace[1682332309] 'read index received' (duration: 178.367467ms)","trace[1682332309] 'applied index is now lower than readState.Index' (duration: 14.11668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:43.932104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.805236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:43.932081Z","caller":"traceutil/trace.go:171","msg":"trace[522663793] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"321.905653ms","start":"2026-07-16T14:17:43.610146Z","end":"2026-07-16T14:17:43.932052Z","steps":["trace[522663793] 'process raft request' (duration: 307.875755ms)","trace[522663793] 'compare' (duration: 13.806371ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:43.932124Z","caller":"traceutil/trace.go:171","msg":"trace[2031567744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"366.870008ms","start":"2026-07-16T14:17:43.565247Z","end":"2026-07-16T14:17:43.932117Z","steps":["trace[2031567744] 'agreement among raft nodes before linearized reading' (duration: 366.815907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:43.932144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:43.565231Z","time spent":"366.909129ms","remote":"127.0.0.1:39018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:17:43.932194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:43.6101Z","time spent":"322.033147ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:43.93236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.288204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-07-16T14:17:43.932415Z","caller":"traceutil/trace.go:171","msg":"trace[281643054] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:616; }","duration":"158.374997ms","start":"2026-07-16T14:17:43.774025Z","end":"2026-07-16T14:17:43.9324Z","steps":["trace[281643054] 'agreement among raft nodes before linearized reading' (duration: 158.1541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:44.13824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.055996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-07-16T14:17:44.138292Z","caller":"traceutil/trace.go:171","msg":"trace[1472803151] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:616; }","duration":"102.15336ms","start":"2026-07-16T14:17:44.036126Z","end":"2026-07-16T14:17:44.138279Z","steps":["trace[1472803151] 'range keys from in-memory index tree' (duration: 101.878572ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:46.116128Z","caller":"traceutil/trace.go:171","msg":"trace[1039971330] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"528.888123ms","start":"2026-07-16T14:17:45.587217Z","end":"2026-07-16T14:17:46.116105Z","steps":["trace[1039971330] 'process raft request' (duration: 528.663947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:46.116269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:45.587198Z","time spent":"528.999056ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:46.116285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.744334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:46.116063Z","caller":"traceutil/trace.go:171","msg":"trace[848463686] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"477.510577ms","start":"2026-07-16T14:17:45.638529Z","end":"2026-07-16T14:17:46.11604Z","steps":["trace[848463686] 'read index received' (duration: 477.477666ms)","trace[848463686] 'applied index is now lower than readState.Index' (duration: 31.751µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:46.116362Z","caller":"traceutil/trace.go:171","msg":"trace[778998717] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:617; }","duration":"477.836466ms","start":"2026-07-16T14:17:45.638497Z","end":"2026-07-16T14:17:46.116333Z","steps":["trace[778998717] 'agreement among raft nodes before linearized reading' (duration: 477.719003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:46.116404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:45.638482Z","time spent":"477.914059ms","remote":"127.0.0.1:39196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-07-16T14:17:46.12132Z","caller":"traceutil/trace.go:171","msg":"trace[517661633] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"451.564676ms","start":"2026-07-16T14:17:45.669733Z","end":"2026-07-16T14:17:46.121297Z","steps":["trace[517661633] 'process raft request' (duration: 451.296188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:46.121645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:45.66971Z","time spent":"451.882935ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:46.12154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.942594ms","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-07-16T14:17:46.121749Z","caller":"traceutil/trace.go:171","msg":"trace[725352803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"379.186631ms","start":"2026-07-16T14:17:45.742541Z","end":"2026-07-16T14:17:46.121728Z","steps":["trace[725352803] 'agreement among raft nodes before linearized reading' (duration: 378.885323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:46.121826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:45.742521Z","time spent":"379.277733ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T14:17:46.121995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.356795ms","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-07-16T14:17:46.122092Z","caller":"traceutil/trace.go:171","msg":"trace[1070581503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"379.531001ms","start":"2026-07-16T14:17:45.742551Z","end":"2026-07-16T14:17:46.122082Z","steps":["trace[1070581503] 'agreement among raft nodes before linearized reading' (duration: 378.936504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:46.122133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:45.74253Z","time spent":"379.594762ms","remote":"127.0.0.1:39278","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-07-16T14:17:46.122363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.381268ms","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-07-16T14:17:46.122471Z","caller":"traceutil/trace.go:171","msg":"trace[1242521500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"378.517652ms","start":"2026-07-16T14:17:45.743936Z","end":"2026-07-16T14:17:46.122454Z","steps":["trace[1242521500] 'agreement among raft nodes before linearized reading' (duration: 378.126941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:46.122585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:45.743921Z","time spent":"378.637005ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T14:17:46.376154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.381684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:46.376262Z","caller":"traceutil/trace.go:171","msg":"trace[1044433740] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"250.280271ms","start":"2026-07-16T14:17:46.125968Z","end":"2026-07-16T14:17:46.376248Z","steps":["trace[1044433740] 'process raft request' (duration: 128.740562ms)","trace[1044433740] 'compare' (duration: 121.298552ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:46.3764Z","caller":"traceutil/trace.go:171","msg":"trace[357910058] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:641; }","duration":"249.139948ms","start":"2026-07-16T14:17:46.127235Z","end":"2026-07-16T14:17:46.376375Z","steps":["trace[357910058] 'read index received' (duration: 10.778185ms)","trace[357910058] 'applied index is now lower than readState.Index' (duration: 238.360253ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:46.376553Z","caller":"traceutil/trace.go:171","msg":"trace[1913716835] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"249.593832ms","start":"2026-07-16T14:17:46.126934Z","end":"2026-07-16T14:17:46.376528Z","steps":["trace[1913716835] 'process raft request' (duration: 249.286003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:46.376755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.460557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-envoy-gateway-rolebinding\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:46.37681Z","caller":"traceutil/trace.go:171","msg":"trace[1330967457] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-envoy-gateway-rolebinding; range_end:; response_count:0; response_revision:621; }","duration":"249.577421ms","start":"2026-07-16T14:17:46.127218Z","end":"2026-07-16T14:17:46.376796Z","steps":["trace[1330967457] 'agreement among raft nodes before linearized reading' (duration: 249.233512ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:46.715018Z","caller":"traceutil/trace.go:171","msg":"trace[665542965] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"153.141583ms","start":"2026-07-16T14:17:46.56186Z","end":"2026-07-16T14:17:46.715001Z","steps":["trace[665542965] 'read index received' (duration: 152.956667ms)","trace[665542965] 'applied index is now lower than readState.Index' (duration: 184.236µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:46.715118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.243345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:46.715136Z","caller":"traceutil/trace.go:171","msg":"trace[1926406900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"153.276476ms","start":"2026-07-16T14:17:46.561853Z","end":"2026-07-16T14:17:46.71513Z","steps":["trace[1926406900] 'agreement among raft nodes before linearized reading' (duration: 153.214515ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:46.715111Z","caller":"traceutil/trace.go:171","msg":"trace[139348848] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"313.886401ms","start":"2026-07-16T14:17:46.401189Z","end":"2026-07-16T14:17:46.715075Z","steps":["trace[139348848] 'process raft request' (duration: 313.710646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:46.715258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:46.401169Z","time spent":"314.020834ms","remote":"127.0.0.1:39100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T14:17:46.95529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.441448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:46.955354Z","caller":"traceutil/trace.go:171","msg":"trace[736226106] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"233.966479ms","start":"2026-07-16T14:17:46.721376Z","end":"2026-07-16T14:17:46.955343Z","steps":["trace[736226106] 'process raft request' (duration: 102.427878ms)","trace[736226106] 'compare' (duration: 131.347746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:48.621942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317253889531947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T14:17:48.655183Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.093425614s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T14:17:48.657992Z","caller":"traceutil/trace.go:171","msg":"trace[510168688] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.098406283s","start":"2026-07-16T14:17:47.559498Z","end":"2026-07-16T14:17:48.657905Z","steps":["trace[510168688] 'process raft request' (duration: 1.096597533s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:48.658143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:47.559467Z","time spent":"1.098594098s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036113,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:48.890076Z","caller":"traceutil/trace.go:171","msg":"trace[1834303841] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"159.182369ms","start":"2026-07-16T14:17:48.73084Z","end":"2026-07-16T14:17:48.890023Z","steps":["trace[1834303841] 'process raft request' (duration: 159.048775ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:48.890027Z","caller":"traceutil/trace.go:171","msg":"trace[1076102317] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"769.071088ms","start":"2026-07-16T14:17:48.120929Z","end":"2026-07-16T14:17:48.89Z","steps":["trace[1076102317] 'read index received' (duration: 534.44551ms)","trace[1076102317] 'applied index is now lower than readState.Index' (duration: 234.622798ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:48.8904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.40617ms","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-07-16T14:17:48.890446Z","caller":"traceutil/trace.go:171","msg":"trace[208420951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"749.514763ms","start":"2026-07-16T14:17:48.140919Z","end":"2026-07-16T14:17:48.890434Z","steps":["trace[208420951] 'agreement among raft nodes before linearized reading' (duration: 749.349418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:48.890474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:48.140887Z","time spent":"749.580715ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T14:17:48.890458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.832496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T14:17:48.890608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.643083ms","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-07-16T14:17:48.89072Z","caller":"traceutil/trace.go:171","msg":"trace[1091671385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"769.821568ms","start":"2026-07-16T14:17:48.12088Z","end":"2026-07-16T14:17:48.890701Z","steps":["trace[1091671385] 'agreement among raft nodes before linearized reading' (duration: 769.247062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:48.890805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.222315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-16T14:17:48.890864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:48.120861Z","time spent":"769.984862ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T14:17:48.891051Z","caller":"traceutil/trace.go:171","msg":"trace[1863866138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"510.351969ms","start":"2026-07-16T14:17:48.380546Z","end":"2026-07-16T14:17:48.890898Z","steps":["trace[1863866138] 'agreement among raft nodes before linearized reading' (duration: 509.923697ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:48.890562Z","caller":"traceutil/trace.go:171","msg":"trace[2074197591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"311.936819ms","start":"2026-07-16T14:17:48.578563Z","end":"2026-07-16T14:17:48.8905Z","steps":["trace[2074197591] 'agreement among raft nodes before linearized reading' (duration: 311.843676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:48.891263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:48.578536Z","time spent":"312.70223ms","remote":"127.0.0.1:39018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:17:48.890661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.213883ms","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-07-16T14:17:48.891336Z","caller":"traceutil/trace.go:171","msg":"trace[1688667209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"511.881721ms","start":"2026-07-16T14:17:48.379439Z","end":"2026-07-16T14:17:48.891321Z","steps":["trace[1688667209] 'agreement among raft nodes before linearized reading' (duration: 511.163191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:48.891119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:48.380535Z","time spent":"510.569234ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T14:17:48.891387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:48.379382Z","time spent":"511.992035ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T14:17:51.70738Z","caller":"traceutil/trace.go:171","msg":"trace[714087850] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"311.432554ms","start":"2026-07-16T14:17:51.395922Z","end":"2026-07-16T14:17:51.707354Z","steps":["trace[714087850] 'process raft request' (duration: 311.301641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:51.707579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:51.395902Z","time spent":"311.552017ms","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T14:17:51.729039Z","caller":"traceutil/trace.go:171","msg":"trace[1520145096] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"130.667937ms","start":"2026-07-16T14:17:51.598351Z","end":"2026-07-16T14:17:51.729019Z","steps":["trace[1520145096] 'process raft request' (duration: 130.531263ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:52.846243Z","caller":"traceutil/trace.go:171","msg":"trace[115027401] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"631.549765ms","start":"2026-07-16T14:17:52.214667Z","end":"2026-07-16T14:17:52.846217Z","steps":["trace[115027401] 'process raft request' (duration: 631.39577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:52.846403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.214659Z","time spent":"631.672228ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T14:17:52.847071Z","caller":"traceutil/trace.go:171","msg":"trace[644245949] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:676; }","duration":"285.947648ms","start":"2026-07-16T14:17:52.561084Z","end":"2026-07-16T14:17:52.847031Z","steps":["trace[644245949] 'read index received' (duration: 285.936058ms)","trace[644245949] 'applied index is now lower than readState.Index' (duration: 8.62µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:52.847341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.233126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:52.847418Z","caller":"traceutil/trace.go:171","msg":"trace[1245726492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"286.345989ms","start":"2026-07-16T14:17:52.561055Z","end":"2026-07-16T14:17:52.847401Z","steps":["trace[1245726492] 'agreement among raft nodes before linearized reading' (duration: 286.140264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:52.847493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.560021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nvtvm\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-16T14:17:52.847623Z","caller":"traceutil/trace.go:171","msg":"trace[1659751454] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nvtvm; range_end:; response_count:1; response_revision:652; }","duration":"258.721045ms","start":"2026-07-16T14:17:52.588886Z","end":"2026-07-16T14:17:52.847607Z","steps":["trace[1659751454] 'agreement among raft nodes before linearized reading' (duration: 258.428927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:53.426352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317253889531998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T14:17:55.562929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00014565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-16T14:17:55.563038Z","caller":"traceutil/trace.go:171","msg":"trace[575617739] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000310035s","start":"2026-07-16T14:17:53.562709Z","end":"2026-07-16T14:17:55.563019Z","steps":["trace[575617739] 'agreement among raft nodes before linearized reading' (duration: 2.000175831s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:55.563092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:53.562688Z","time spent":"2.000390656s","remote":"127.0.0.1:39018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T14:17:55.58282Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.078679779s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T14:17:55.583301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367180279s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:55.583409Z","caller":"traceutil/trace.go:171","msg":"trace[1424093086] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:652; }","duration":"1.367294892s","start":"2026-07-16T14:17:54.216083Z","end":"2026-07-16T14:17:55.583378Z","steps":["trace[1424093086] 'range keys from in-memory index tree' (duration: 1.367162829s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:55.583949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079730247s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T14:17:55.584418Z","caller":"traceutil/trace.go:171","msg":"trace[54758270] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:677; }","duration":"2.658085233s","start":"2026-07-16T14:17:52.92631Z","end":"2026-07-16T14:17:55.584395Z","steps":["trace[54758270] 'read index received' (duration: 577.837582ms)","trace[54758270] 'applied index is now lower than readState.Index' (duration: 2.080246471s)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:17:55.5847Z","caller":"traceutil/trace.go:171","msg":"trace[1372711937] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"2.730911933s","start":"2026-07-16T14:17:52.85377Z","end":"2026-07-16T14:17:55.584682Z","steps":["trace[1372711937] 'process raft request' (duration: 2.73042033s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:55.584816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.853752Z","time spent":"2.730991215s","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:55.584848Z","caller":"traceutil/trace.go:171","msg":"trace[1617200202] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"2.725191529s","start":"2026-07-16T14:17:52.859636Z","end":"2026-07-16T14:17:55.584828Z","steps":["trace[1617200202] 'process raft request' (duration: 2.724613674s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:55.584886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.658560275s","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-07-16T14:17:55.584956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.859625Z","time spent":"2.725263641s","remote":"127.0.0.1:39032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:55.584957Z","caller":"traceutil/trace.go:171","msg":"trace[1043911323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:658; }","duration":"2.658662608s","start":"2026-07-16T14:17:52.926275Z","end":"2026-07-16T14:17:55.584937Z","steps":["trace[1043911323] 'agreement among raft nodes before linearized reading' (duration: 2.658402411s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:55.58501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.926257Z","time spent":"2.65874062s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T14:17:55.58502Z","caller":"traceutil/trace.go:171","msg":"trace[986344995] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"2.734197532s","start":"2026-07-16T14:17:52.850804Z","end":"2026-07-16T14:17:55.585002Z","steps":["trace[986344995] 'process raft request' (duration: 653.320895ms)","trace[986344995] 'compare' (duration: 2.079436719s)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:55.585031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.658644758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T14:17:55.585058Z","caller":"traceutil/trace.go:171","msg":"trace[1382187264] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"2.724382247s","start":"2026-07-16T14:17:52.86063Z","end":"2026-07-16T14:17:55.585012Z","steps":["trace[1382187264] 'process raft request' (duration: 2.723685109s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:55.585118Z","caller":"traceutil/trace.go:171","msg":"trace[1370760119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:658; }","duration":"2.658782711s","start":"2026-07-16T14:17:52.926313Z","end":"2026-07-16T14:17:55.585095Z","steps":["trace[1370760119] 'agreement among raft nodes before linearized reading' (duration: 2.658391281s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:55.585156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.656881209s","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-07-16T14:17:55.585172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.926292Z","time spent":"2.658871253s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T14:17:55.585203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.860612Z","time spent":"2.724518461s","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:17:55.585213Z","caller":"traceutil/trace.go:171","msg":"trace[1591553504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:658; }","duration":"2.656947611s","start":"2026-07-16T14:17:52.928247Z","end":"2026-07-16T14:17:55.585195Z","steps":["trace[1591553504] 'agreement among raft nodes before linearized reading' (duration: 2.656742855s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:55.585254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.928242Z","time spent":"2.657000633s","remote":"127.0.0.1:39278","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-07-16T14:17:55.585085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.850781Z","time spent":"2.734273913s","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T14:17:55.585123Z","caller":"traceutil/trace.go:171","msg":"trace[1769243294] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"2.733552494s","start":"2026-07-16T14:17:52.851562Z","end":"2026-07-16T14:17:55.585115Z","steps":["trace[1769243294] 'process raft request' (duration: 2.732498096s)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:55.585349Z","caller":"traceutil/trace.go:171","msg":"trace[317318995] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"2.731585161s","start":"2026-07-16T14:17:52.853755Z","end":"2026-07-16T14:17:55.58534Z","steps":["trace[317318995] 'process raft request' (duration: 2.730365709s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:55.585415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.853735Z","time spent":"2.731645373s","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T14:17:55.585433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.658911974s","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-07-16T14:17:55.585428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.851541Z","time spent":"2.733815151s","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T14:17:55.585466Z","caller":"traceutil/trace.go:171","msg":"trace[640288846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:658; }","duration":"2.658973866s","start":"2026-07-16T14:17:52.926482Z","end":"2026-07-16T14:17:55.585455Z","steps":["trace[640288846] 'agreement among raft nodes before linearized reading' (duration: 2.658871543s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:17:55.585495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:17:52.926464Z","time spent":"2.659024626s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T14:17:58.80777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.750783ms","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":"warn","ts":"2026-07-16T14:17:58.807776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.397782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T14:17:58.807856Z","caller":"traceutil/trace.go:171","msg":"trace[1167182238] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:701; }","duration":"254.886427ms","start":"2026-07-16T14:17:58.552948Z","end":"2026-07-16T14:17:58.807834Z","steps":["trace[1167182238] 'count revisions from in-memory index tree' (duration: 254.666211ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:58.807867Z","caller":"traceutil/trace.go:171","msg":"trace[494365246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"243.529895ms","start":"2026-07-16T14:17:58.564314Z","end":"2026-07-16T14:17:58.807844Z","steps":["trace[494365246] 'range keys from in-memory index tree' (duration: 243.290128ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:58.80868Z","caller":"traceutil/trace.go:171","msg":"trace[1250033107] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"195.556893ms","start":"2026-07-16T14:17:58.613103Z","end":"2026-07-16T14:17:58.80866Z","steps":["trace[1250033107] 'read index received' (duration: 195.514752ms)","trace[1250033107] 'applied index is now lower than readState.Index' (duration: 41.121µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:17:58.808894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.793819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nvtvm\" ","response":"range_response_count:1 size:17520"} {"level":"info","ts":"2026-07-16T14:17:58.808843Z","caller":"traceutil/trace.go:171","msg":"trace[1370560290] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"236.017632ms","start":"2026-07-16T14:17:58.572803Z","end":"2026-07-16T14:17:58.80882Z","steps":["trace[1370560290] 'process raft request' (duration: 235.750025ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:58.808944Z","caller":"traceutil/trace.go:171","msg":"trace[1651703669] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nvtvm; range_end:; response_count:1; response_revision:702; }","duration":"195.878721ms","start":"2026-07-16T14:17:58.613052Z","end":"2026-07-16T14:17:58.808931Z","steps":["trace[1651703669] 'agreement among raft nodes before linearized reading' (duration: 195.680326ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:17:58.811039Z","caller":"traceutil/trace.go:171","msg":"trace[673744207] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"197.78752ms","start":"2026-07-16T14:17:58.613231Z","end":"2026-07-16T14:17:58.811018Z","steps":["trace[673744207] 'process raft request' (duration: 197.542353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:02.316179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.355577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-16T14:18:02.316285Z","caller":"traceutil/trace.go:171","msg":"trace[1839619577] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:744; }","duration":"528.498591ms","start":"2026-07-16T14:18:01.787761Z","end":"2026-07-16T14:18:02.316259Z","steps":["trace[1839619577] 'range keys from in-memory index tree' (duration: 528.161413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:02.31634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:01.787745Z","time spent":"528.581383ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-07-16T14:18:02.41625Z","caller":"traceutil/trace.go:171","msg":"trace[1823596991] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"613.715791ms","start":"2026-07-16T14:18:01.802497Z","end":"2026-07-16T14:18:02.416213Z","steps":["trace[1823596991] 'process raft request' (duration: 613.454165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:02.416517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:01.802478Z","time spent":"613.857045ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:02.467891Z","caller":"traceutil/trace.go:171","msg":"trace[498060545] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"534.40243ms","start":"2026-07-16T14:18:01.933456Z","end":"2026-07-16T14:18:02.467859Z","steps":["trace[498060545] 'process raft request' (duration: 534.223076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:02.468226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.514115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-07-16T14:18:02.468303Z","caller":"traceutil/trace.go:171","msg":"trace[1580233511] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"495.639157ms","start":"2026-07-16T14:18:01.972646Z","end":"2026-07-16T14:18:02.468285Z","steps":["trace[1580233511] 'agreement among raft nodes before linearized reading' (duration: 495.294308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:02.468408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:01.972626Z","time spent":"495.76459ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188519,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-16T14:18:02.468269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:01.933413Z","time spent":"534.735378ms","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:02.467878Z","caller":"traceutil/trace.go:171","msg":"trace[1491585818] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:771; }","duration":"495.121274ms","start":"2026-07-16T14:18:01.97269Z","end":"2026-07-16T14:18:02.467812Z","steps":["trace[1491585818] 'read index received' (duration: 443.266012ms)","trace[1491585818] 'applied index is now lower than readState.Index' (duration: 51.853822ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:02.468953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.228852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-07-16T14:18:02.469016Z","caller":"traceutil/trace.go:171","msg":"trace[1807981157] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"296.339235ms","start":"2026-07-16T14:18:02.172656Z","end":"2026-07-16T14:18:02.468996Z","steps":["trace[1807981157] 'agreement among raft nodes before linearized reading' (duration: 296.181501ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:02.816449Z","caller":"traceutil/trace.go:171","msg":"trace[3203263] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"151.202118ms","start":"2026-07-16T14:18:02.665224Z","end":"2026-07-16T14:18:02.816426Z","steps":["trace[3203263] 'process raft request' (duration: 151.043894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:13.302016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317253889532396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T14:18:13.649008Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.118147377s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T14:18:13.668556Z","caller":"traceutil/trace.go:171","msg":"trace[1403539148] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"1.137767309s","start":"2026-07-16T14:18:12.530767Z","end":"2026-07-16T14:18:13.668534Z","steps":["trace[1403539148] 'process raft request' (duration: 1.118389422s)","trace[1403539148] 'compare' (duration: 19.06298ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:13.668677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:12.530743Z","time spent":"1.137867361s","remote":"127.0.0.1:39278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:13.675014Z","caller":"traceutil/trace.go:171","msg":"trace[1559973556] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:850; }","duration":"873.340782ms","start":"2026-07-16T14:18:12.801655Z","end":"2026-07-16T14:18:13.674995Z","steps":["trace[1559973556] 'read index received' (duration: 847.507016ms)","trace[1559973556] 'applied index is now lower than readState.Index' (duration: 25.833246ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:18:13.675114Z","caller":"traceutil/trace.go:171","msg":"trace[1005308706] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"960.3022ms","start":"2026-07-16T14:18:12.714789Z","end":"2026-07-16T14:18:13.675091Z","steps":["trace[1005308706] 'process raft request' (duration: 960.118486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:13.675281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.513973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T14:18:13.675275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.606347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-16T14:18:13.675332Z","caller":"traceutil/trace.go:171","msg":"trace[1254821447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"112.649586ms","start":"2026-07-16T14:18:13.562671Z","end":"2026-07-16T14:18:13.675321Z","steps":["trace[1254821447] 'agreement among raft nodes before linearized reading' (duration: 112.533223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:13.675394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:12.714773Z","time spent":"960.513484ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T14:18:13.675359Z","caller":"traceutil/trace.go:171","msg":"trace[1060136677] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:822; }","duration":"873.728591ms","start":"2026-07-16T14:18:12.801615Z","end":"2026-07-16T14:18:13.675344Z","steps":["trace[1060136677] 'agreement among raft nodes before linearized reading' (duration: 873.490945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:18:13.675519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T14:18:12.801599Z","time spent":"873.884764ms","remote":"127.0.0.1:39032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"info","ts":"2026-07-16T14:18:27.025412Z","caller":"traceutil/trace.go:171","msg":"trace[2042374349] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"201.145705ms","start":"2026-07-16T14:18:26.824236Z","end":"2026-07-16T14:18:27.025382Z","steps":["trace[2042374349] 'process raft request' (duration: 200.935351ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:27.087432Z","caller":"traceutil/trace.go:171","msg":"trace[1159093136] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"256.297287ms","start":"2026-07-16T14:18:26.831105Z","end":"2026-07-16T14:18:27.087403Z","steps":["trace[1159093136] 'process raft request' (duration: 251.892194ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:58.061345Z","caller":"traceutil/trace.go:171","msg":"trace[349449499] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"120.204402ms","start":"2026-07-16T14:18:57.94111Z","end":"2026-07-16T14:18:58.061315Z","steps":["trace[349449499] 'process raft request' (duration: 118.167716ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:58.061396Z","caller":"traceutil/trace.go:171","msg":"trace[193423593] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"117.88012ms","start":"2026-07-16T14:18:57.943486Z","end":"2026-07-16T14:18:58.061366Z","steps":["trace[193423593] 'process raft request' (duration: 117.817949ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:18:58.061511Z","caller":"traceutil/trace.go:171","msg":"trace[1318165252] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"119.844145ms","start":"2026-07-16T14:18:57.941643Z","end":"2026-07-16T14:18:58.061487Z","steps":["trace[1318165252] 'read index received' (duration: 117.647946ms)","trace[1318165252] 'applied index is now lower than readState.Index' (duration: 2.195139ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T14:18:58.061708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.041789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T14:18:58.061782Z","caller":"traceutil/trace.go:171","msg":"trace[1896961946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"120.143881ms","start":"2026-07-16T14:18:57.941621Z","end":"2026-07-16T14:18:58.061765Z","steps":["trace[1896961946] 'agreement among raft nodes before linearized reading' (duration: 119.958348ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:19:26.18497Z","caller":"traceutil/trace.go:171","msg":"trace[1247566631] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"101.645645ms","start":"2026-07-16T14:19:26.083294Z","end":"2026-07-16T14:19:26.184939Z","steps":["trace[1247566631] 'read index received' (duration: 101.432992ms)","trace[1247566631] 'applied index is now lower than readState.Index' (duration: 205.593µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T14:19:26.18504Z","caller":"traceutil/trace.go:171","msg":"trace[637840538] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"204.622834ms","start":"2026-07-16T14:19:25.980392Z","end":"2026-07-16T14:19:26.185015Z","steps":["trace[637840538] 'process raft request' (duration: 204.40518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T14:19:26.185266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.901229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-16T14:19:26.18527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.94979ms","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-07-16T14:19:26.18539Z","caller":"traceutil/trace.go:171","msg":"trace[50379551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1192; }","duration":"102.119483ms","start":"2026-07-16T14:19:26.08325Z","end":"2026-07-16T14:19:26.18537Z","steps":["trace[50379551] 'agreement among raft nodes before linearized reading' (duration: 101.845299ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T14:19:26.185402Z","caller":"traceutil/trace.go:171","msg":"trace[1141605178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1192; }","duration":"102.020322ms","start":"2026-07-16T14:19:26.083298Z","end":"2026-07-16T14:19:26.185318Z","steps":["trace[1141605178] 'agreement among raft nodes before linearized reading' (duration: 101.803228ms)"],"step_count":1}