{"level":"warn","ts":"2026-08-11T12:51:24.54594Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T12:51:24.546039Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-11T12:51:24.546107Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T12:51:24.546116Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-08-11T12:51:24.546135Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-11T12:51:24.546763Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-08-11T12:51:24.546834Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-11T12:51:24.569291Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"22.104715ms"} {"level":"info","ts":"2026-08-11T12:51:24.632759Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-08-11T12:51:24.632888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T12:51:24.632963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-08-11T12:51:24.632972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T12:51:24.632984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-08-11T12:51:24.633022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-08-11T12:51:24.675639Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T12:51:24.6785Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T12:51:24.680425Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-11T12:51:24.684682Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T12:51:24.684812Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T12:51:24.684936Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T12:51:24.684949Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T12:51:24.685391Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T12:51:24.688205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-08-11T12:51:24.688685Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-08-11T12:51:24.690204Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-11T12:51:24.690378Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-08-11T12:51:24.690447Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-08-11T12:51:24.690497Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T12:51:24.690546Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T12:51:24.733756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T12:51:24.73385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T12:51:24.733876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-08-11T12:51:24.733892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-08-11T12:51:24.733898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-08-11T12:51:24.733908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-08-11T12:51:24.733916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-08-11T12:51:24.738303Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:51:24.739005Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T12:51:24.739139Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T12:51:24.739241Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T12:51:24.739499Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:51:24.739632Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:51:24.739665Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:51:24.741683Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-08-11T12:51:24.741992Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T12:51:24.742245Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T12:51:24.742319Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T12:51:30.075693Z","caller":"traceutil/trace.go:171","msg":"trace[210999854] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"126.853476ms","start":"2026-08-11T12:51:29.94879Z","end":"2026-08-11T12:51:30.075643Z","steps":["trace[210999854] 'process raft request' (duration: 82.148024ms)","trace[210999854] 'compare' (duration: 44.432758ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:51:43.271311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.543724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T12:51:43.271696Z","caller":"traceutil/trace.go:171","msg":"trace[763419606] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"870.043533ms","start":"2026-08-11T12:51:42.401624Z","end":"2026-08-11T12:51:43.271667Z","steps":["trace[763419606] 'process raft request' (duration: 526.149718ms)","trace[763419606] 'compare' (duration: 342.206999ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:51:43.271746Z","caller":"traceutil/trace.go:171","msg":"trace[886530787] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"168.774447ms","start":"2026-08-11T12:51:43.102941Z","end":"2026-08-11T12:51:43.271716Z","steps":["trace[886530787] 'read index received' (duration: 44.581µs)","trace[886530787] 'applied index is now lower than readState.Index' (duration: 168.727546ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:51:43.271918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.96125ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:51:43.272001Z","caller":"traceutil/trace.go:171","msg":"trace[1634770690] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:413; }","duration":"169.063922ms","start":"2026-08-11T12:51:43.102929Z","end":"2026-08-11T12:51:43.271993Z","steps":["trace[1634770690] 'agreement among raft nodes before linearized reading' (duration: 168.94494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:51:43.271919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:51:42.401598Z","time spent":"870.207635ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:51:44.257406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540497509899430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T12:51:44.301762Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.019169024s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T12:51:44.302293Z","caller":"traceutil/trace.go:171","msg":"trace[961742387] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"545.596231ms","start":"2026-08-11T12:51:43.756675Z","end":"2026-08-11T12:51:44.302271Z","steps":["trace[961742387] 'read index received' (duration: 545.238136ms)","trace[961742387] 'applied index is now lower than readState.Index' (duration: 357.455µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:51:44.302349Z","caller":"traceutil/trace.go:171","msg":"trace[1758598426] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.019878885s","start":"2026-08-11T12:51:43.282464Z","end":"2026-08-11T12:51:44.302343Z","steps":["trace[1758598426] 'process raft request' (duration: 1.019506399s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:51:44.30243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:51:43.282442Z","time spent":"1.019923215s","remote":"127.0.0.1:39112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:51:44.302471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.400132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-11T12:51:44.302552Z","caller":"traceutil/trace.go:171","msg":"trace[1538958736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"506.548694ms","start":"2026-08-11T12:51:43.795986Z","end":"2026-08-11T12:51:44.302535Z","steps":["trace[1538958736] 'agreement among raft nodes before linearized reading' (duration: 506.400452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:51:44.30257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.901256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-11T12:51:44.302586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:51:43.795961Z","time spent":"506.616795ms","remote":"127.0.0.1:39200","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-08-11T12:51:44.302594Z","caller":"traceutil/trace.go:171","msg":"trace[189628103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"545.968077ms","start":"2026-08-11T12:51:43.756618Z","end":"2026-08-11T12:51:44.302586Z","steps":["trace[189628103] 'agreement among raft nodes before linearized reading' (duration: 545.914626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:51:44.302614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:51:43.756599Z","time spent":"546.011538ms","remote":"127.0.0.1:39200","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-08-11T12:51:44.872064Z","caller":"traceutil/trace.go:171","msg":"trace[638846652] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"565.556949ms","start":"2026-08-11T12:51:44.306393Z","end":"2026-08-11T12:51:44.87195Z","steps":["trace[638846652] 'process raft request' (duration: 518.549465ms)","trace[638846652] 'compare' (duration: 46.866322ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:51:44.872239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:51:44.306369Z","time spent":"565.782282ms","remote":"127.0.0.1:39200","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-08-11T12:51:44.876815Z","caller":"traceutil/trace.go:171","msg":"trace[392164867] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"569.875384ms","start":"2026-08-11T12:51:44.306899Z","end":"2026-08-11T12:51:44.876775Z","steps":["trace[392164867] 'process raft request' (duration: 569.62531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:51:44.877014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:51:44.306891Z","time spent":"570.026547ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:51:44.882065Z","caller":"traceutil/trace.go:171","msg":"trace[1746053357] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:428; }","duration":"500.601806ms","start":"2026-08-11T12:51:44.381443Z","end":"2026-08-11T12:51:44.882045Z","steps":["trace[1746053357] 'read index received' (duration: 443.581281ms)","trace[1746053357] 'applied index is now lower than readState.Index' (duration: 57.019385ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:51:44.882153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.91268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T12:51:44.882178Z","caller":"traceutil/trace.go:171","msg":"trace[373343097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"500.997152ms","start":"2026-08-11T12:51:44.381174Z","end":"2026-08-11T12:51:44.882171Z","steps":["trace[373343097] 'agreement among raft nodes before linearized reading' (duration: 500.927101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:51:44.882205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:51:44.381149Z","time spent":"501.051182ms","remote":"127.0.0.1:39200","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-08-11T12:52:21.183532Z","caller":"traceutil/trace.go:171","msg":"trace[1367048767] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"222.014433ms","start":"2026-08-11T12:52:20.961495Z","end":"2026-08-11T12:52:21.183509Z","steps":["trace[1367048767] 'read index received' (duration: 20.38267ms)","trace[1367048767] 'applied index is now lower than readState.Index' (duration: 201.630993ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:52:21.183541Z","caller":"traceutil/trace.go:171","msg":"trace[58870984] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"222.898095ms","start":"2026-08-11T12:52:20.96061Z","end":"2026-08-11T12:52:21.183508Z","steps":["trace[58870984] 'process raft request' (duration: 131.037436ms)","trace[58870984] 'compare' (duration: 91.593425ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:52:21.183623Z","caller":"traceutil/trace.go:171","msg":"trace[260454082] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"221.77341ms","start":"2026-08-11T12:52:20.961823Z","end":"2026-08-11T12:52:21.183597Z","steps":["trace[260454082] 'process raft request' (duration: 221.543577ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:21.183844Z","caller":"traceutil/trace.go:171","msg":"trace[1314064990] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"220.706385ms","start":"2026-08-11T12:52:20.963127Z","end":"2026-08-11T12:52:21.183833Z","steps":["trace[1314064990] 'process raft request' (duration: 220.31618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.18406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.53958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-08-11T12:52:21.184123Z","caller":"traceutil/trace.go:171","msg":"trace[378699724] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:556; }","duration":"222.657432ms","start":"2026-08-11T12:52:20.961449Z","end":"2026-08-11T12:52:21.184106Z","steps":["trace[378699724] 'agreement among raft nodes before linearized reading' (duration: 222.428699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.188725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.382878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-11T12:52:21.188793Z","caller":"traceutil/trace.go:171","msg":"trace[1015314669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"125.475389ms","start":"2026-08-11T12:52:21.063298Z","end":"2026-08-11T12:52:21.188773Z","steps":["trace[1015314669] 'agreement among raft nodes before linearized reading' (duration: 125.335847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.188953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.658559ms","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-08-11T12:52:21.188982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.53339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-11T12:52:21.189016Z","caller":"traceutil/trace.go:171","msg":"trace[717647609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"127.785771ms","start":"2026-08-11T12:52:21.061219Z","end":"2026-08-11T12:52:21.189005Z","steps":["trace[717647609] 'agreement among raft nodes before linearized reading' (duration: 127.639519ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:21.189044Z","caller":"traceutil/trace.go:171","msg":"trace[479619238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"106.647262ms","start":"2026-08-11T12:52:21.082381Z","end":"2026-08-11T12:52:21.189029Z","steps":["trace[479619238] 'agreement among raft nodes before linearized reading' (duration: 106.50616ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:21.188708Z","caller":"traceutil/trace.go:171","msg":"trace[494402683] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"183.529595ms","start":"2026-08-11T12:52:21.005152Z","end":"2026-08-11T12:52:21.188682Z","steps":["trace[494402683] 'process raft request' (duration: 182.531291ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:21.706556Z","caller":"traceutil/trace.go:171","msg":"trace[662490485] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"508.297395ms","start":"2026-08-11T12:52:21.198222Z","end":"2026-08-11T12:52:21.706519Z","steps":["trace[662490485] 'process raft request' (duration: 450.902429ms)","trace[662490485] 'compare' (duration: 56.96123ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:52:21.706657Z","caller":"traceutil/trace.go:171","msg":"trace[1549920780] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"507.22974ms","start":"2026-08-11T12:52:21.199395Z","end":"2026-08-11T12:52:21.706625Z","steps":["trace[1549920780] 'process raft request' (duration: 507.026858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.706768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:21.198215Z","time spent":"508.447837ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:52:21.706839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.394478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"warn","ts":"2026-08-11T12:52:21.706839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:21.199388Z","time spent":"507.347912ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:52:21.706856Z","caller":"traceutil/trace.go:171","msg":"trace[2115896824] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"504.516873ms","start":"2026-08-11T12:52:21.202313Z","end":"2026-08-11T12:52:21.70683Z","steps":["trace[2115896824] 'process raft request' (duration: 504.235189ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:21.706664Z","caller":"traceutil/trace.go:171","msg":"trace[1167077194] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:582; }","duration":"503.210896ms","start":"2026-08-11T12:52:21.203435Z","end":"2026-08-11T12:52:21.706646Z","steps":["trace[1167077194] 'read index received' (duration: 4.162297ms)","trace[1167077194] 'applied index is now lower than readState.Index' (duration: 499.047479ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:52:21.706883Z","caller":"traceutil/trace.go:171","msg":"trace[1237681410] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:562; }","duration":"503.464319ms","start":"2026-08-11T12:52:21.203408Z","end":"2026-08-11T12:52:21.706873Z","steps":["trace[1237681410] 'agreement among raft nodes before linearized reading' (duration: 503.306467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.707528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:21.203392Z","time spent":"504.113307ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14487,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"warn","ts":"2026-08-11T12:52:21.706977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:21.202292Z","time spent":"504.631086ms","remote":"127.0.0.1:39112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:52:21.707106Z","caller":"traceutil/trace.go:171","msg":"trace[1610024177] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"502.609708ms","start":"2026-08-11T12:52:21.204479Z","end":"2026-08-11T12:52:21.707089Z","steps":["trace[1610024177] 'process raft request' (duration: 502.115351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.708208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:21.203066Z","time spent":"505.080831ms","remote":"127.0.0.1:39430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4656,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:52:21.707258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.439497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-08-11T12:52:21.708698Z","caller":"traceutil/trace.go:171","msg":"trace[1245116888] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:562; }","duration":"498.869826ms","start":"2026-08-11T12:52:21.209772Z","end":"2026-08-11T12:52:21.708641Z","steps":["trace[1245116888] 'agreement among raft nodes before linearized reading' (duration: 497.403416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.707276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.981685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T12:52:21.708767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:21.209754Z","time spent":"498.996928ms","remote":"127.0.0.1:39140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":562,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-08-11T12:52:21.708782Z","caller":"traceutil/trace.go:171","msg":"trace[628895815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"456.514196ms","start":"2026-08-11T12:52:21.252244Z","end":"2026-08-11T12:52:21.708759Z","steps":["trace[628895815] 'agreement among raft nodes before linearized reading' (duration: 454.977945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.708849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:21.252226Z","time spent":"456.605457ms","remote":"127.0.0.1:38946","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-08-11T12:52:21.707286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.296224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-08-11T12:52:21.70905Z","caller":"traceutil/trace.go:171","msg":"trace[1318679740] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:562; }","duration":"499.072898ms","start":"2026-08-11T12:52:21.209961Z","end":"2026-08-11T12:52:21.709034Z","steps":["trace[1318679740] 'agreement among raft nodes before linearized reading' (duration: 497.267624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.709104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:21.209955Z","time spent":"499.13381ms","remote":"127.0.0.1:39140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":544,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-08-11T12:52:21.707277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.317165ms","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-08-11T12:52:21.709491Z","caller":"traceutil/trace.go:171","msg":"trace[1010162091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"183.574865ms","start":"2026-08-11T12:52:21.525901Z","end":"2026-08-11T12:52:21.709476Z","steps":["trace[1010162091] 'agreement among raft nodes before linearized reading' (duration: 181.301564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.707421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.9353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-08-11T12:52:21.709684Z","caller":"traceutil/trace.go:171","msg":"trace[1937230519] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:562; }","duration":"445.195371ms","start":"2026-08-11T12:52:21.264471Z","end":"2026-08-11T12:52:21.709667Z","steps":["trace[1937230519] 'agreement among raft nodes before linearized reading' (duration: 442.728907ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.709743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:21.264453Z","time spent":"445.271812ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329976,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-08-11T12:52:23.71289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540497509899770,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T12:52:23.810538Z","caller":"traceutil/trace.go:171","msg":"trace[1587546979] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"661.281338ms","start":"2026-08-11T12:52:23.149233Z","end":"2026-08-11T12:52:23.810514Z","steps":["trace[1587546979] 'process raft request' (duration: 660.66181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.810675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.149209Z","time spent":"661.40157ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:52:23.819618Z","caller":"traceutil/trace.go:171","msg":"trace[2129788650] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"666.132484ms","start":"2026-08-11T12:52:23.153457Z","end":"2026-08-11T12:52:23.819589Z","steps":["trace[2129788650] 'process raft request' (duration: 665.930781ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:23.819718Z","caller":"traceutil/trace.go:171","msg":"trace[4587621] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"652.040902ms","start":"2026-08-11T12:52:23.167648Z","end":"2026-08-11T12:52:23.819689Z","steps":["trace[4587621] 'process raft request' (duration: 651.85822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.81978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.153439Z","time spent":"666.261046ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:52:23.819927Z","caller":"traceutil/trace.go:171","msg":"trace[604050893] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"652.212555ms","start":"2026-08-11T12:52:23.167706Z","end":"2026-08-11T12:52:23.819918Z","steps":["trace[604050893] 'process raft request' (duration: 651.84952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.819988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.167685Z","time spent":"652.264015ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:52:23.820023Z","caller":"traceutil/trace.go:171","msg":"trace[842637468] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"650.118596ms","start":"2026-08-11T12:52:23.169893Z","end":"2026-08-11T12:52:23.820012Z","steps":["trace[842637468] 'process raft request' (duration: 649.902333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.819966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.167628Z","time spent":"652.137264ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T12:52:23.82009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.16987Z","time spent":"650.189017ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:52:23.820005Z","caller":"traceutil/trace.go:171","msg":"trace[928641342] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"652.208795ms","start":"2026-08-11T12:52:23.167724Z","end":"2026-08-11T12:52:23.819933Z","steps":["trace[928641342] 'process raft request' (duration: 652.003522ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:23.82016Z","caller":"traceutil/trace.go:171","msg":"trace[2019846112] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"641.465888ms","start":"2026-08-11T12:52:23.178684Z","end":"2026-08-11T12:52:23.82015Z","steps":["trace[2019846112] 'process raft request' (duration: 641.201674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.820249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.178665Z","time spent":"641.531498ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:52:23.820245Z","caller":"traceutil/trace.go:171","msg":"trace[1814950701] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"641.492228ms","start":"2026-08-11T12:52:23.178741Z","end":"2026-08-11T12:52:23.820233Z","steps":["trace[1814950701] 'process raft request' (duration: 641.281355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.820425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.178736Z","time spent":"641.61899ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:52:23.820462Z","caller":"traceutil/trace.go:171","msg":"trace[1768181742] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"612.894998ms","start":"2026-08-11T12:52:23.207554Z","end":"2026-08-11T12:52:23.820449Z","steps":["trace[1768181742] 'process raft request' (duration: 612.588504ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:23.820564Z","caller":"traceutil/trace.go:171","msg":"trace[149667748] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"605.592028ms","start":"2026-08-11T12:52:23.214963Z","end":"2026-08-11T12:52:23.820555Z","steps":["trace[149667748] 'process raft request' (duration: 605.388196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.820634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.214942Z","time spent":"605.650649ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T12:52:23.820678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.207537Z","time spent":"613.009269ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T12:52:23.820754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.167718Z","time spent":"653.012006ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:52:23.820851Z","caller":"traceutil/trace.go:171","msg":"trace[294679897] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"596.812638ms","start":"2026-08-11T12:52:23.22403Z","end":"2026-08-11T12:52:23.820843Z","steps":["trace[294679897] 'process raft request' (duration: 596.424643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.821464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.224003Z","time spent":"597.435106ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:52:23.822886Z","caller":"traceutil/trace.go:171","msg":"trace[1094316141] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:590; }","duration":"610.075899ms","start":"2026-08-11T12:52:23.212786Z","end":"2026-08-11T12:52:23.822862Z","steps":["trace[1094316141] 'read index received' (duration: 597.132652ms)","trace[1094316141] 'applied index is now lower than readState.Index' (duration: 12.941937ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:52:23.823266Z","caller":"traceutil/trace.go:171","msg":"trace[1066730381] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"484.669477ms","start":"2026-08-11T12:52:23.338584Z","end":"2026-08-11T12:52:23.823253Z","steps":["trace[1066730381] 'process raft request' (duration: 482.281735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.823528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.732548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-11T12:52:23.823566Z","caller":"traceutil/trace.go:171","msg":"trace[355981018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"610.790719ms","start":"2026-08-11T12:52:23.212766Z","end":"2026-08-11T12:52:23.823557Z","steps":["trace[355981018] 'agreement among raft nodes before linearized reading' (duration: 610.627717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.823553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.338549Z","time spent":"484.90359ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T12:52:23.823594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.212757Z","time spent":"610.83164ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T12:52:23.824403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.903611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:52:23.824453Z","caller":"traceutil/trace.go:171","msg":"trace[1309836084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"570.009563ms","start":"2026-08-11T12:52:23.254432Z","end":"2026-08-11T12:52:23.824442Z","steps":["trace[1309836084] 'agreement among raft nodes before linearized reading' (duration: 569.929891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.8245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:23.254413Z","time spent":"570.079693ms","remote":"127.0.0.1:38944","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-08-11T12:52:23.825006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.718993ms","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-08-11T12:52:23.825045Z","caller":"traceutil/trace.go:171","msg":"trace[40969769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"105.777995ms","start":"2026-08-11T12:52:23.719258Z","end":"2026-08-11T12:52:23.825036Z","steps":["trace[40969769] 'agreement among raft nodes before linearized reading' (duration: 105.712814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.825098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.152434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-11T12:52:23.82513Z","caller":"traceutil/trace.go:171","msg":"trace[662786676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"110.223245ms","start":"2026-08-11T12:52:23.714898Z","end":"2026-08-11T12:52:23.825122Z","steps":["trace[662786676] 'agreement among raft nodes before linearized reading' (duration: 110.144354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:23.825261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.370935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-11T12:52:23.825285Z","caller":"traceutil/trace.go:171","msg":"trace[1106014923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"112.440575ms","start":"2026-08-11T12:52:23.712839Z","end":"2026-08-11T12:52:23.82528Z","steps":["trace[1106014923] 'agreement among raft nodes before linearized reading' (duration: 112.368494ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:30.738933Z","caller":"traceutil/trace.go:171","msg":"trace[1076266164] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"483.639493ms","start":"2026-08-11T12:52:30.255266Z","end":"2026-08-11T12:52:30.738905Z","steps":["trace[1076266164] 'read index received' (duration: 482.951533ms)","trace[1076266164] 'applied index is now lower than readState.Index' (duration: 687.2µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:52:30.739098Z","caller":"traceutil/trace.go:171","msg":"trace[2082401266] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"510.710559ms","start":"2026-08-11T12:52:30.228333Z","end":"2026-08-11T12:52:30.739043Z","steps":["trace[2082401266] 'process raft request' (duration: 510.017129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:30.739145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.849665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T12:52:30.739193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.408072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gqrdg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-11T12:52:30.739236Z","caller":"traceutil/trace.go:171","msg":"trace[315332409] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gqrdg; range_end:; response_count:1; response_revision:679; }","duration":"458.511724ms","start":"2026-08-11T12:52:30.280714Z","end":"2026-08-11T12:52:30.739225Z","steps":["trace[315332409] 'agreement among raft nodes before linearized reading' (duration: 458.332132ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:30.739245Z","caller":"traceutil/trace.go:171","msg":"trace[907809368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"484.000918ms","start":"2026-08-11T12:52:30.255217Z","end":"2026-08-11T12:52:30.739218Z","steps":["trace[907809368] 'agreement among raft nodes before linearized reading' (duration: 483.827906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:30.739276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:30.280692Z","time spent":"458.576125ms","remote":"127.0.0.1:39112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-gqrdg\" "} {"level":"warn","ts":"2026-08-11T12:52:30.739304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:30.255197Z","time spent":"484.099399ms","remote":"127.0.0.1:38946","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-08-11T12:52:30.73936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:30.228298Z","time spent":"510.923871ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:52:31.534584Z","caller":"traceutil/trace.go:171","msg":"trace[556117037] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"780.242361ms","start":"2026-08-11T12:52:30.754316Z","end":"2026-08-11T12:52:31.534558Z","steps":["trace[556117037] 'process raft request' (duration: 780.069849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:31.53484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:30.754282Z","time spent":"780.378114ms","remote":"127.0.0.1:39112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:52:32.632682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061046294s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T12:52:32.633013Z","caller":"traceutil/trace.go:171","msg":"trace[236999977] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"156.012767ms","start":"2026-08-11T12:52:32.476982Z","end":"2026-08-11T12:52:32.632994Z","steps":["trace[236999977] 'process raft request' (duration: 155.968726ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:32.633279Z","caller":"traceutil/trace.go:171","msg":"trace[1458066331] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"637.526561ms","start":"2026-08-11T12:52:31.995741Z","end":"2026-08-11T12:52:32.633268Z","steps":["trace[1458066331] 'process raft request' (duration: 637.127726ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:32.633357Z","caller":"traceutil/trace.go:171","msg":"trace[1053400120] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:711; }","duration":"1.377380153s","start":"2026-08-11T12:52:31.255952Z","end":"2026-08-11T12:52:32.633332Z","steps":["trace[1053400120] 'read index received' (duration: 278.49363ms)","trace[1053400120] 'applied index is now lower than readState.Index' (duration: 1.098883643s)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:52:32.633562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377584866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:52:32.633601Z","caller":"traceutil/trace.go:171","msg":"trace[2099843109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"1.377697458s","start":"2026-08-11T12:52:31.255896Z","end":"2026-08-11T12:52:32.633594Z","steps":["trace[2099843109] 'agreement among raft nodes before linearized reading' (duration: 1.377585456s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:32.633627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:31.255811Z","time spent":"1.377810509s","remote":"127.0.0.1:38946","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-08-11T12:52:32.633804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056762726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T12:52:32.633798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073903317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-11T12:52:32.634042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.009237ms","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-08-11T12:52:32.633892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.137867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-11T12:52:32.634092Z","caller":"traceutil/trace.go:171","msg":"trace[1369041135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"684.085828ms","start":"2026-08-11T12:52:31.949981Z","end":"2026-08-11T12:52:32.634067Z","steps":["trace[1369041135] 'agreement among raft nodes before linearized reading' (duration: 683.702463ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:32.634115Z","caller":"traceutil/trace.go:171","msg":"trace[1573413416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"715.40913ms","start":"2026-08-11T12:52:31.918684Z","end":"2026-08-11T12:52:32.634093Z","steps":["trace[1573413416] 'agreement among raft nodes before linearized reading' (duration: 715.029965ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:32.633984Z","caller":"traceutil/trace.go:171","msg":"trace[1470336786] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:683; }","duration":"1.0569979s","start":"2026-08-11T12:52:31.57696Z","end":"2026-08-11T12:52:32.633958Z","steps":["trace[1470336786] 'agreement among raft nodes before linearized reading' (duration: 1.056770617s)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:32.634105Z","caller":"traceutil/trace.go:171","msg":"trace[911758973] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:683; }","duration":"1.074216012s","start":"2026-08-11T12:52:31.559814Z","end":"2026-08-11T12:52:32.63403Z","steps":["trace[911758973] 'agreement among raft nodes before linearized reading' (duration: 1.073858617s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:32.634303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:31.995711Z","time spent":"637.693283ms","remote":"127.0.0.1:39106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:52:32.633332Z","caller":"traceutil/trace.go:171","msg":"trace[1128584384] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.836015344s","start":"2026-08-11T12:52:30.797281Z","end":"2026-08-11T12:52:32.633296Z","steps":["trace[1128584384] 'process raft request' (duration: 774.25294ms)","trace[1128584384] 'compare' (duration: 1.060906942s)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:52:32.634406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:30.797248Z","time spent":"1.837102998s","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T12:52:32.634154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:31.918662Z","time spent":"715.48197ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T12:52:32.633989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.294601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-11T12:52:32.634569Z","caller":"traceutil/trace.go:171","msg":"trace[816110691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"759.908439ms","start":"2026-08-11T12:52:31.87465Z","end":"2026-08-11T12:52:32.634558Z","steps":["trace[816110691] 'agreement among raft nodes before linearized reading' (duration: 759.079598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:32.634602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:31.874629Z","time spent":"759.96373ms","remote":"127.0.0.1:39200","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-08-11T12:52:32.633885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.605574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T12:52:32.634752Z","caller":"traceutil/trace.go:171","msg":"trace[540449161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"754.568457ms","start":"2026-08-11T12:52:31.880173Z","end":"2026-08-11T12:52:32.634741Z","steps":["trace[540449161] 'agreement among raft nodes before linearized reading' (duration: 753.502063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:32.63479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:31.880158Z","time spent":"754.621108ms","remote":"127.0.0.1:39200","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-08-11T12:52:32.634218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:31.576917Z","time spent":"1.057281543s","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-08-11T12:52:32.634148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:31.949957Z","time spent":"684.181929ms","remote":"127.0.0.1:39200","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-08-11T12:52:32.636827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:31.559793Z","time spent":"1.074517596s","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-11T12:52:34.633333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.42879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:52:34.633427Z","caller":"traceutil/trace.go:171","msg":"trace[1884491883] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:714; }","duration":"157.575222ms","start":"2026-08-11T12:52:34.47583Z","end":"2026-08-11T12:52:34.633405Z","steps":["trace[1884491883] 'range keys from in-memory index tree' (duration: 157.275368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:34.63345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.568652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:52:34.633575Z","caller":"traceutil/trace.go:171","msg":"trace[503234826] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:714; }","duration":"157.776125ms","start":"2026-08-11T12:52:34.475772Z","end":"2026-08-11T12:52:34.633548Z","steps":["trace[503234826] 'count revisions from in-memory index tree' (duration: 157.341918ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:34.643712Z","caller":"traceutil/trace.go:171","msg":"trace[712546757] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"166.674184ms","start":"2026-08-11T12:52:34.477023Z","end":"2026-08-11T12:52:34.643697Z","steps":["trace[712546757] 'read index received' (duration: 166.484891ms)","trace[712546757] 'applied index is now lower than readState.Index' (duration: 188.303µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:52:34.643766Z","caller":"traceutil/trace.go:171","msg":"trace[411059239] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"167.21288ms","start":"2026-08-11T12:52:34.476531Z","end":"2026-08-11T12:52:34.643744Z","steps":["trace[411059239] 'process raft request' (duration: 167.041988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:34.643867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.824656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:52:34.643928Z","caller":"traceutil/trace.go:171","msg":"trace[1995684015] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:715; }","duration":"166.943998ms","start":"2026-08-11T12:52:34.476969Z","end":"2026-08-11T12:52:34.643913Z","steps":["trace[1995684015] 'agreement among raft nodes before linearized reading' (duration: 166.817106ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:37.624973Z","caller":"traceutil/trace.go:171","msg":"trace[319738268] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"107.497224ms","start":"2026-08-11T12:52:37.517448Z","end":"2026-08-11T12:52:37.624945Z","steps":["trace[319738268] 'process raft request' (duration: 107.2051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:49.261515Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540497509900354,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T12:52:49.582544Z","caller":"traceutil/trace.go:171","msg":"trace[55676774] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"827.809167ms","start":"2026-08-11T12:52:48.754705Z","end":"2026-08-11T12:52:49.582514Z","steps":["trace[55676774] 'process raft request' (duration: 827.622454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:49.582831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:48.754649Z","time spent":"828.07327ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:52:49.736652Z","caller":"traceutil/trace.go:171","msg":"trace[1009047865] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:881; }","duration":"975.415903ms","start":"2026-08-11T12:52:48.761208Z","end":"2026-08-11T12:52:49.736624Z","steps":["trace[1009047865] 'read index received' (duration: 821.173059ms)","trace[1009047865] 'applied index is now lower than readState.Index' (duration: 154.241384ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:52:49.736694Z","caller":"traceutil/trace.go:171","msg":"trace[366411814] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"981.559684ms","start":"2026-08-11T12:52:48.755095Z","end":"2026-08-11T12:52:49.736655Z","steps":["trace[366411814] 'process raft request' (duration: 981.306501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:49.736882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:48.755076Z","time spent":"981.701046ms","remote":"127.0.0.1:39200","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-08-11T12:52:49.737013Z","caller":"traceutil/trace.go:171","msg":"trace[2113300426] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"310.743488ms","start":"2026-08-11T12:52:49.426254Z","end":"2026-08-11T12:52:49.736998Z","steps":["trace[2113300426] 'process raft request' (duration: 310.319672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:49.737158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:49.426219Z","time spent":"310.846049ms","remote":"127.0.0.1:39174","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-08-11T12:52:49.737316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.099233ms","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-08-11T12:52:49.737427Z","caller":"traceutil/trace.go:171","msg":"trace[775252059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:848; }","duration":"976.223144ms","start":"2026-08-11T12:52:48.761181Z","end":"2026-08-11T12:52:49.737404Z","steps":["trace[775252059] 'agreement among raft nodes before linearized reading' (duration: 975.92112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:49.737489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:48.761173Z","time spent":"976.305065ms","remote":"127.0.0.1:39200","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-08-11T12:52:49.742563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.838164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T12:52:49.742666Z","caller":"traceutil/trace.go:171","msg":"trace[1549381089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:849; }","duration":"980.995556ms","start":"2026-08-11T12:52:48.761656Z","end":"2026-08-11T12:52:49.742651Z","steps":["trace[1549381089] 'agreement among raft nodes before linearized reading' (duration: 980.773073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:49.742669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.847871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-11T12:52:49.742731Z","caller":"traceutil/trace.go:171","msg":"trace[1923197555] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:849; }","duration":"394.956123ms","start":"2026-08-11T12:52:49.347761Z","end":"2026-08-11T12:52:49.742717Z","steps":["trace[1923197555] 'agreement among raft nodes before linearized reading' (duration: 394.847501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:49.742755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:49.347731Z","time spent":"395.018143ms","remote":"127.0.0.1:38960","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.44\" "} {"level":"warn","ts":"2026-08-11T12:52:49.742771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.545948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:52:49.74252Z","caller":"traceutil/trace.go:171","msg":"trace[859385087] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"116.012672ms","start":"2026-08-11T12:52:49.626453Z","end":"2026-08-11T12:52:49.742466Z","steps":["trace[859385087] 'process raft request' (duration: 115.83273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:49.74279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:48.761618Z","time spent":"981.124938ms","remote":"127.0.0.1:39200","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-08-11T12:52:49.742845Z","caller":"traceutil/trace.go:171","msg":"trace[610406642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"452.75036ms","start":"2026-08-11T12:52:49.290084Z","end":"2026-08-11T12:52:49.742834Z","steps":["trace[610406642] 'agreement among raft nodes before linearized reading' (duration: 452.527707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:49.742987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:49.290054Z","time spent":"452.926963ms","remote":"127.0.0.1:38946","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-08-11T12:54:52.52033Z","caller":"traceutil/trace.go:171","msg":"trace[1203680024] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"277.233471ms","start":"2026-08-11T12:54:52.243055Z","end":"2026-08-11T12:54:52.520289Z","steps":["trace[1203680024] 'process raft request' (duration: 277.032195ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:54:52.520503Z","caller":"traceutil/trace.go:171","msg":"trace[1519008552] linearizableReadLoop","detail":"{readStateIndex:1482; appliedIndex:1482; }","duration":"267.030941ms","start":"2026-08-11T12:54:52.253427Z","end":"2026-08-11T12:54:52.520458Z","steps":["trace[1519008552] 'read index received' (duration: 267.01568ms)","trace[1519008552] 'applied index is now lower than readState.Index' (duration: 12.851µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:54:52.520758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.299748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:54:52.52079Z","caller":"traceutil/trace.go:171","msg":"trace[1913328112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1419; }","duration":"267.37152ms","start":"2026-08-11T12:54:52.25341Z","end":"2026-08-11T12:54:52.520782Z","steps":["trace[1913328112] 'agreement among raft nodes before linearized reading' (duration: 267.274187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:54:53.021252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540497509901744,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T12:54:53.424155Z","caller":"traceutil/trace.go:171","msg":"trace[1169008586] linearizableReadLoop","detail":"{readStateIndex:1483; appliedIndex:1482; }","duration":"903.471019ms","start":"2026-08-11T12:54:52.520651Z","end":"2026-08-11T12:54:53.424122Z","steps":["trace[1169008586] 'read index received' (duration: 903.419667ms)","trace[1169008586] 'applied index is now lower than readState.Index' (duration: 50.092µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:54:53.424249Z","caller":"traceutil/trace.go:171","msg":"trace[1356711043] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"1.045850021s","start":"2026-08-11T12:54:52.378351Z","end":"2026-08-11T12:54:53.424201Z","steps":["trace[1356711043] 'process raft request' (duration: 1.045561363s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:54:53.424551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.97029ms","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-08-11T12:54:53.424574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:54:52.378315Z","time spent":"1.0461376s","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:54:53.42457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.325221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T12:54:53.424615Z","caller":"traceutil/trace.go:171","msg":"trace[1841989144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1420; }","duration":"995.119304ms","start":"2026-08-11T12:54:52.429475Z","end":"2026-08-11T12:54:53.424595Z","steps":["trace[1841989144] 'agreement among raft nodes before linearized reading' (duration: 994.828576ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:54:53.42474Z","caller":"traceutil/trace.go:171","msg":"trace[1627908583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1420; }","duration":"994.548348ms","start":"2026-08-11T12:54:52.430141Z","end":"2026-08-11T12:54:53.42469Z","steps":["trace[1627908583] 'agreement among raft nodes before linearized reading' (duration: 994.062514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:54:53.424788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:54:52.429444Z","time spent":"995.33397ms","remote":"127.0.0.1:39200","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-08-11T12:54:53.424811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:54:52.430109Z","time spent":"994.685231ms","remote":"127.0.0.1:39200","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-08-11T12:54:53.424959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011133971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-11T12:54:53.424994Z","caller":"traceutil/trace.go:171","msg":"trace[1864115906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1420; }","duration":"1.011204913s","start":"2026-08-11T12:54:52.413781Z","end":"2026-08-11T12:54:53.424986Z","steps":["trace[1864115906] 'agreement among raft nodes before linearized reading' (duration: 1.011009687s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:54:53.425019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:54:52.413759Z","time spent":"1.011255944s","remote":"127.0.0.1:39200","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-08-11T12:54:53.53642Z","caller":"traceutil/trace.go:171","msg":"trace[648870962] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"112.121468ms","start":"2026-08-11T12:54:53.424268Z","end":"2026-08-11T12:54:53.53639Z","steps":["trace[648870962] 'read index received' (duration: 57.380512ms)","trace[648870962] 'applied index is now lower than readState.Index' (duration: 54.739896ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:54:53.536575Z","caller":"traceutil/trace.go:171","msg":"trace[766141008] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"203.703062ms","start":"2026-08-11T12:54:53.332859Z","end":"2026-08-11T12:54:53.536562Z","steps":["trace[766141008] 'process raft request' (duration: 148.595115ms)","trace[766141008] 'compare' (duration: 54.702806ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:54:53.537024Z","caller":"traceutil/trace.go:171","msg":"trace[1890253042] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"105.801968ms","start":"2026-08-11T12:54:53.431196Z","end":"2026-08-11T12:54:53.536998Z","steps":["trace[1890253042] 'process raft request' (duration: 105.753127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:54:53.537018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015001549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:54:53.537179Z","caller":"traceutil/trace.go:171","msg":"trace[846537788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1422; }","duration":"1.015173905s","start":"2026-08-11T12:54:52.521937Z","end":"2026-08-11T12:54:53.537111Z","steps":["trace[846537788] 'agreement among raft nodes before linearized reading' (duration: 1.014594528s)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:54:53.537217Z","caller":"traceutil/trace.go:171","msg":"trace[926829588] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"106.716484ms","start":"2026-08-11T12:54:53.430484Z","end":"2026-08-11T12:54:53.537201Z","steps":["trace[926829588] 'process raft request' (duration: 105.86351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:54:53.537311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:54:52.521921Z","time spent":"1.015314378s","remote":"127.0.0.1:38944","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-08-11T12:54:53.537402Z","caller":"traceutil/trace.go:171","msg":"trace[852268654] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"106.853728ms","start":"2026-08-11T12:54:53.430517Z","end":"2026-08-11T12:54:53.537371Z","steps":["trace[852268654] 'process raft request' (duration: 106.325343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:54:53.537099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.707646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T12:54:53.537884Z","caller":"traceutil/trace.go:171","msg":"trace[1142182019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1422; }","duration":"718.508569ms","start":"2026-08-11T12:54:52.819343Z","end":"2026-08-11T12:54:53.537851Z","steps":["trace[1142182019] 'agreement among raft nodes before linearized reading' (duration: 717.400087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:54:53.537997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:54:52.819323Z","time spent":"718.637252ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-11T12:54:54.046386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540497509901751,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T12:54:54.385373Z","caller":"traceutil/trace.go:171","msg":"trace[825316881] linearizableReadLoop","detail":"{readStateIndex:1488; appliedIndex:1487; }","duration":"839.07145ms","start":"2026-08-11T12:54:53.54628Z","end":"2026-08-11T12:54:54.385351Z","steps":["trace[825316881] 'read index received' (duration: 838.880384ms)","trace[825316881] 'applied index is now lower than readState.Index' (duration: 190.156µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:54:54.385509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.210634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:54:54.385547Z","caller":"traceutil/trace.go:171","msg":"trace[193851991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1425; }","duration":"839.279825ms","start":"2026-08-11T12:54:53.546258Z","end":"2026-08-11T12:54:54.385538Z","steps":["trace[193851991] 'agreement among raft nodes before linearized reading' (duration: 839.183972ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:54:54.385531Z","caller":"traceutil/trace.go:171","msg":"trace[1855739799] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"839.644276ms","start":"2026-08-11T12:54:53.54583Z","end":"2026-08-11T12:54:54.385474Z","steps":["trace[1855739799] 'process raft request' (duration: 839.386559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:54:54.385588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:54:53.546252Z","time spent":"839.329357ms","remote":"127.0.0.1:38944","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-08-11T12:54:54.385702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:54:53.545817Z","time spent":"839.79954ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:55:00.620504Z","caller":"traceutil/trace.go:171","msg":"trace[1819853463] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1514; }","duration":"159.757071ms","start":"2026-08-11T12:55:00.460721Z","end":"2026-08-11T12:55:00.620478Z","steps":["trace[1819853463] 'read index received' (duration: 159.586976ms)","trace[1819853463] 'applied index is now lower than readState.Index' (duration: 169.175µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:55:00.620669Z","caller":"traceutil/trace.go:171","msg":"trace[1936964635] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"180.185847ms","start":"2026-08-11T12:55:00.440403Z","end":"2026-08-11T12:55:00.620589Z","steps":["trace[1936964635] 'process raft request' (duration: 179.92543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:55:00.620766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.026808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-08-11T12:55:00.620801Z","caller":"traceutil/trace.go:171","msg":"trace[1606316017] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1449; }","duration":"160.103391ms","start":"2026-08-11T12:55:00.460686Z","end":"2026-08-11T12:55:00.62079Z","steps":["trace[1606316017] 'agreement among raft nodes before linearized reading' (duration: 159.933586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:55:00.620894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.007334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-08-11T12:55:00.621023Z","caller":"traceutil/trace.go:171","msg":"trace[2112899830] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1449; }","duration":"113.154348ms","start":"2026-08-11T12:55:00.507848Z","end":"2026-08-11T12:55:00.621003Z","steps":["trace[2112899830] 'agreement among raft nodes before linearized reading' (duration: 112.85643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:55:00.930746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.56311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:1 size:2025"} {"level":"info","ts":"2026-08-11T12:55:00.931Z","caller":"traceutil/trace.go:171","msg":"trace[909205370] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:1; response_revision:1450; }","duration":"135.864018ms","start":"2026-08-11T12:55:00.795121Z","end":"2026-08-11T12:55:00.930985Z","steps":["trace[909205370] 'range keys from in-memory index tree' (duration: 135.500028ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:55:03.926477Z","caller":"traceutil/trace.go:171","msg":"trace[627191798] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"135.439658ms","start":"2026-08-11T12:55:03.791001Z","end":"2026-08-11T12:55:03.92644Z","steps":["trace[627191798] 'process raft request' (duration: 129.755042ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:55:03.926903Z","caller":"traceutil/trace.go:171","msg":"trace[84574608] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"135.006386ms","start":"2026-08-11T12:55:03.791866Z","end":"2026-08-11T12:55:03.926873Z","steps":["trace[84574608] 'process raft request' (duration: 134.852721ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:55:10.246036Z","caller":"traceutil/trace.go:171","msg":"trace[47630958] linearizableReadLoop","detail":"{readStateIndex:1550; appliedIndex:1548; }","duration":"276.193924ms","start":"2026-08-11T12:55:09.969824Z","end":"2026-08-11T12:55:10.246018Z","steps":["trace[47630958] 'read index received' (duration: 16.052002ms)","trace[47630958] 'applied index is now lower than readState.Index' (duration: 260.141082ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:55:10.246126Z","caller":"traceutil/trace.go:171","msg":"trace[1859899201] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"295.458982ms","start":"2026-08-11T12:55:09.950658Z","end":"2026-08-11T12:55:10.246117Z","steps":["trace[1859899201] 'process raft request' (duration: 262.733715ms)","trace[1859899201] 'compare' (duration: 32.431239ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:55:10.246271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.43529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T12:55:10.246307Z","caller":"traceutil/trace.go:171","msg":"trace[1425192707] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1482; }","duration":"276.508033ms","start":"2026-08-11T12:55:09.969789Z","end":"2026-08-11T12:55:10.246297Z","steps":["trace[1425192707] 'agreement among raft nodes before linearized reading' (duration: 276.443081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:55:10.246384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.817469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-08-11T12:55:10.246517Z","caller":"traceutil/trace.go:171","msg":"trace[906327273] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:1; response_revision:1482; }","duration":"254.993044ms","start":"2026-08-11T12:55:09.991505Z","end":"2026-08-11T12:55:10.246498Z","steps":["trace[906327273] 'agreement among raft nodes before linearized reading' (duration: 254.697596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:55:10.246626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.155592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-controller-manager\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-08-11T12:55:10.246764Z","caller":"traceutil/trace.go:171","msg":"trace[961031163] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-controller-manager; range_end:; response_count:1; response_revision:1482; }","duration":"238.347378ms","start":"2026-08-11T12:55:10.008393Z","end":"2026-08-11T12:55:10.24674Z","steps":["trace[961031163] 'agreement among raft nodes before linearized reading' (duration: 237.854764ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:55:10.868608Z","caller":"traceutil/trace.go:171","msg":"trace[1612035895] linearizableReadLoop","detail":"{readStateIndex:1551; appliedIndex:1550; }","duration":"149.019893ms","start":"2026-08-11T12:55:10.719563Z","end":"2026-08-11T12:55:10.868583Z","steps":["trace[1612035895] 'read index received' (duration: 148.857579ms)","trace[1612035895] 'applied index is now lower than readState.Index' (duration: 161.494µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:55:10.86876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.175907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:55:10.86873Z","caller":"traceutil/trace.go:171","msg":"trace[93531787] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"167.660922ms","start":"2026-08-11T12:55:10.701047Z","end":"2026-08-11T12:55:10.868708Z","steps":["trace[93531787] 'process raft request' (duration: 167.398395ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:55:10.868796Z","caller":"traceutil/trace.go:171","msg":"trace[1358124359] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1483; }","duration":"149.249968ms","start":"2026-08-11T12:55:10.719534Z","end":"2026-08-11T12:55:10.868784Z","steps":["trace[1358124359] 'agreement among raft nodes before linearized reading' (duration: 149.172816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:55:10.868901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.475907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-11T12:55:10.868916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.089212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-08-11T12:55:10.868968Z","caller":"traceutil/trace.go:171","msg":"trace[2070894887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1483; }","duration":"117.57422ms","start":"2026-08-11T12:55:10.751381Z","end":"2026-08-11T12:55:10.868955Z","steps":["trace[2070894887] 'agreement among raft nodes before linearized reading' (duration: 117.418646ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:55:10.869005Z","caller":"traceutil/trace.go:171","msg":"trace[969809435] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:1483; }","duration":"125.174043ms","start":"2026-08-11T12:55:10.743796Z","end":"2026-08-11T12:55:10.86897Z","steps":["trace[969809435] 'agreement among raft nodes before linearized reading' (duration: 125.05478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:55:10.868909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.386405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:public-info-viewer\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-08-11T12:55:10.869083Z","caller":"traceutil/trace.go:171","msg":"trace[1661127174] range","detail":"{range_begin:/registry/clusterrolebindings/system:public-info-viewer; range_end:; response_count:1; response_revision:1483; }","duration":"145.620421ms","start":"2026-08-11T12:55:10.723447Z","end":"2026-08-11T12:55:10.869067Z","steps":["trace[1661127174] 'agreement among raft nodes before linearized reading' (duration: 145.355704ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:55:11.006255Z","caller":"traceutil/trace.go:171","msg":"trace[83428331] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1551; }","duration":"132.818459ms","start":"2026-08-11T12:55:10.873412Z","end":"2026-08-11T12:55:11.006231Z","steps":["trace[83428331] 'read index received' (duration: 95.205862ms)","trace[83428331] 'applied index is now lower than readState.Index' (duration: 37.611677ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:55:11.006304Z","caller":"traceutil/trace.go:171","msg":"trace[40549477] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"133.077996ms","start":"2026-08-11T12:55:10.873184Z","end":"2026-08-11T12:55:11.006262Z","steps":["trace[40549477] 'process raft request' (duration: 95.52185ms)","trace[40549477] 'compare' (duration: 37.412872ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:55:11.006413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.979613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-08-11T12:55:11.006495Z","caller":"traceutil/trace.go:171","msg":"trace[388959418] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:1484; }","duration":"133.101607ms","start":"2026-08-11T12:55:10.873372Z","end":"2026-08-11T12:55:11.006474Z","steps":["trace[388959418] 'agreement among raft nodes before linearized reading' (duration: 132.952993ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:55:17.899786Z","caller":"traceutil/trace.go:171","msg":"trace[75024264] linearizableReadLoop","detail":"{readStateIndex:1574; appliedIndex:1573; }","duration":"148.224341ms","start":"2026-08-11T12:55:17.751531Z","end":"2026-08-11T12:55:17.899755Z","steps":["trace[75024264] 'read index received' (duration: 148.041296ms)","trace[75024264] 'applied index is now lower than readState.Index' (duration: 181.955µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:55:17.899883Z","caller":"traceutil/trace.go:171","msg":"trace[2059109963] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"152.093073ms","start":"2026-08-11T12:55:17.747758Z","end":"2026-08-11T12:55:17.899851Z","steps":["trace[2059109963] 'process raft request' (duration: 151.862957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:55:17.900101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.63561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/local-path-storage/\" range_end:\"/registry/persistentvolumeclaims/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:55:17.900217Z","caller":"traceutil/trace.go:171","msg":"trace[1474690002] range","detail":"{range_begin:/registry/persistentvolumeclaims/local-path-storage/; range_end:/registry/persistentvolumeclaims/local-path-storage0; response_count:0; response_revision:1505; }","duration":"104.796044ms","start":"2026-08-11T12:55:17.795401Z","end":"2026-08-11T12:55:17.900197Z","steps":["trace[1474690002] 'agreement among raft nodes before linearized reading' (duration: 104.620229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:55:17.900487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.609555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"info","ts":"2026-08-11T12:55:17.900544Z","caller":"traceutil/trace.go:171","msg":"trace[733363340] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1505; }","duration":"100.695487ms","start":"2026-08-11T12:55:17.799836Z","end":"2026-08-11T12:55:17.900532Z","steps":["trace[733363340] 'agreement among raft nodes before linearized reading' (duration: 100.494551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:55:17.900545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.987581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:84 size:62345"} {"level":"info","ts":"2026-08-11T12:55:17.900626Z","caller":"traceutil/trace.go:171","msg":"trace[335882027] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:84; response_revision:1505; }","duration":"149.114244ms","start":"2026-08-11T12:55:17.751492Z","end":"2026-08-11T12:55:17.900606Z","steps":["trace[335882027] 'agreement among raft nodes before linearized reading' (duration: 148.419396ms)"],"step_count":1}