{"level":"warn","ts":"2026-03-17T21:53:38.24652Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:53:38.246659Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110: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://162.253.55.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110: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-03-17T21:53:38.246776Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:53:38.246805Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-17T21:53:38.246843Z","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-03-17T21:53:38.249244Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-03-17T21:53:38.249389Z","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://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110: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-03-17T21:53:38.255321Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.535819ms"} {"level":"info","ts":"2026-03-17T21:53:38.264129Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-03-17T21:53:38.264216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-17T21:53:38.264239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-03-17T21:53:38.264256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-17T21:53:38.264266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-03-17T21:53:38.264307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-03-17T21:53:38.281402Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-17T21:53:38.283783Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-17T21:53:38.284897Z","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-03-17T21:53:38.287072Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-17T21:53:38.290148Z","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-03-17T21:53:38.29029Z","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-03-17T21:53:38.290324Z","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-03-17T21:53:38.290463Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-17T21:53:38.292153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-03-17T21:53:38.292374Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-17T21:53:38.293179Z","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-03-17T21:53:38.293473Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-17T21:53:38.293514Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-17T21:53:38.29357Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-17T21:53:38.293616Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-17T21:53:38.967195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-17T21:53:38.967306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-17T21:53:38.967335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-03-17T21:53:38.967357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-03-17T21:53:38.967374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-17T21:53:38.967407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-03-17T21:53:38.96743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-17T21:53:38.969566Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:53:38.969587Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:53:38.969652Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:53:38.969539Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-03-17T21:53:38.971475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-17T21:53:38.971825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-03-17T21:53:38.971827Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:53:38.971908Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:53:38.971974Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:53:38.976682Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-17T21:53:38.976721Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-17T21:53:46.654685Z","caller":"traceutil/trace.go:171","msg":"trace[326976730] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"105.857894ms","start":"2026-03-17T21:53:46.548792Z","end":"2026-03-17T21:53:46.65465Z","steps":["trace[326976730] 'process raft request' (duration: 105.40857ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.026552Z","caller":"traceutil/trace.go:171","msg":"trace[1674385811] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"227.950299ms","start":"2026-03-17T21:53:46.798578Z","end":"2026-03-17T21:53:47.026528Z","steps":["trace[1674385811] 'read index received' (duration: 227.249344ms)","trace[1674385811] 'applied index is now lower than readState.Index' (duration: 699.715µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:47.026656Z","caller":"traceutil/trace.go:171","msg":"trace[1558894109] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"228.08387ms","start":"2026-03-17T21:53:46.798561Z","end":"2026-03-17T21:53:47.026644Z","steps":["trace[1558894109] 'process raft request' (duration: 227.255594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:47.026848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.238622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-17T21:53:47.027024Z","caller":"traceutil/trace.go:171","msg":"trace[326071387] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:301; }","duration":"228.449203ms","start":"2026-03-17T21:53:46.798552Z","end":"2026-03-17T21:53:47.027002Z","steps":["trace[326071387] 'agreement among raft nodes before linearized reading' (duration: 228.206451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:47.027181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.194232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-17T21:53:47.027266Z","caller":"traceutil/trace.go:171","msg":"trace[1683925720] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:302; }","duration":"178.317624ms","start":"2026-03-17T21:53:46.848929Z","end":"2026-03-17T21:53:47.027246Z","steps":["trace[1683925720] 'agreement among raft nodes before linearized reading' (duration: 178.148823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:47.027486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.605366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-17T21:53:47.027432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.019099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-17T21:53:47.027525Z","caller":"traceutil/trace.go:171","msg":"trace[1249684349] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:302; }","duration":"129.685086ms","start":"2026-03-17T21:53:46.897832Z","end":"2026-03-17T21:53:47.027517Z","steps":["trace[1249684349] 'agreement among raft nodes before linearized reading' (duration: 129.616656ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.027553Z","caller":"traceutil/trace.go:171","msg":"trace[227063719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:302; }","duration":"119.214151ms","start":"2026-03-17T21:53:46.908315Z","end":"2026-03-17T21:53:47.02753Z","steps":["trace[227063719] 'agreement among raft nodes before linearized reading' (duration: 118.9727ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.027739Z","caller":"traceutil/trace.go:171","msg":"trace[1701021471] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"228.879697ms","start":"2026-03-17T21:53:46.79885Z","end":"2026-03-17T21:53:47.027729Z","steps":["trace[1701021471] 'process raft request' (duration: 228.133041ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.23993Z","caller":"traceutil/trace.go:171","msg":"trace[2001821584] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"202.878481ms","start":"2026-03-17T21:53:47.037019Z","end":"2026-03-17T21:53:47.239898Z","steps":["trace[2001821584] 'process raft request' (duration: 171.591258ms)","trace[2001821584] 'compare' (duration: 31.050461ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:47.240328Z","caller":"traceutil/trace.go:171","msg":"trace[1968453104] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"202.665799ms","start":"2026-03-17T21:53:47.037629Z","end":"2026-03-17T21:53:47.240295Z","steps":["trace[1968453104] 'process raft request' (duration: 202.414597ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.240373Z","caller":"traceutil/trace.go:171","msg":"trace[843840041] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"203.230423ms","start":"2026-03-17T21:53:47.037123Z","end":"2026-03-17T21:53:47.240353Z","steps":["trace[843840041] 'process raft request' (duration: 202.678459ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.240255Z","caller":"traceutil/trace.go:171","msg":"trace[325942738] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:314; }","duration":"192.613537ms","start":"2026-03-17T21:53:47.047567Z","end":"2026-03-17T21:53:47.240181Z","steps":["trace[325942738] 'read index received' (duration: 24.722739ms)","trace[325942738] 'applied index is now lower than readState.Index' (duration: 167.889288ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:53:47.240603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.0154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-17T21:53:47.240706Z","caller":"traceutil/trace.go:171","msg":"trace[58200568] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:307; }","duration":"193.143901ms","start":"2026-03-17T21:53:47.047547Z","end":"2026-03-17T21:53:47.240691Z","steps":["trace[58200568] 'agreement among raft nodes before linearized reading' (duration: 192.94331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:47.240772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.964708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-17T21:53:47.240447Z","caller":"traceutil/trace.go:171","msg":"trace[869683803] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"201.166906ms","start":"2026-03-17T21:53:47.039159Z","end":"2026-03-17T21:53:47.240326Z","steps":["trace[869683803] 'process raft request' (duration: 200.962785ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.241073Z","caller":"traceutil/trace.go:171","msg":"trace[350768994] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:307; }","duration":"142.162939ms","start":"2026-03-17T21:53:47.098696Z","end":"2026-03-17T21:53:47.240859Z","steps":["trace[350768994] 'agreement among raft nodes before linearized reading' (duration: 141.975188ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.450855Z","caller":"traceutil/trace.go:171","msg":"trace[159796848] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:320; }","duration":"145.693838ms","start":"2026-03-17T21:53:47.305136Z","end":"2026-03-17T21:53:47.45083Z","steps":["trace[159796848] 'read index received' (duration: 55.502699ms)","trace[159796848] 'applied index is now lower than readState.Index' (duration: 90.189759ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:47.450901Z","caller":"traceutil/trace.go:171","msg":"trace[2084497793] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"202.157415ms","start":"2026-03-17T21:53:47.248718Z","end":"2026-03-17T21:53:47.450876Z","steps":["trace[2084497793] 'process raft request' (duration: 111.926765ms)","trace[2084497793] 'compare' (duration: 90.054778ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:53:47.451049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.231183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-17T21:53:47.451163Z","caller":"traceutil/trace.go:171","msg":"trace[1797710209] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:309; }","duration":"151.374175ms","start":"2026-03-17T21:53:47.299742Z","end":"2026-03-17T21:53:47.451116Z","steps":["trace[1797710209] 'agreement among raft nodes before linearized reading' (duration: 151.183443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:47.451076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.004785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-17T21:53:47.451581Z","caller":"traceutil/trace.go:171","msg":"trace[1987227601] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:309; }","duration":"102.541779ms","start":"2026-03-17T21:53:47.349007Z","end":"2026-03-17T21:53:47.451549Z","steps":["trace[1987227601] 'agreement among raft nodes before linearized reading' (duration: 101.963934ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.737065Z","caller":"traceutil/trace.go:171","msg":"trace[1244364558] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"274.799792ms","start":"2026-03-17T21:53:47.462227Z","end":"2026-03-17T21:53:47.737026Z","steps":["trace[1244364558] 'process raft request' (duration: 274.645291ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.737099Z","caller":"traceutil/trace.go:171","msg":"trace[1136118228] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:322; }","duration":"237.642952ms","start":"2026-03-17T21:53:47.499435Z","end":"2026-03-17T21:53:47.737078Z","steps":["trace[1136118228] 'read index received' (duration: 140.073523ms)","trace[1136118228] 'applied index is now lower than readState.Index' (duration: 97.568199ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:47.737154Z","caller":"traceutil/trace.go:171","msg":"trace[785291715] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"274.903733ms","start":"2026-03-17T21:53:47.462229Z","end":"2026-03-17T21:53:47.737133Z","steps":["trace[785291715] 'process raft request' (duration: 177.355805ms)","trace[785291715] 'compare' (duration: 97.154565ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:47.737404Z","caller":"traceutil/trace.go:171","msg":"trace[1673458209] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"275.140455ms","start":"2026-03-17T21:53:47.46225Z","end":"2026-03-17T21:53:47.737391Z","steps":["trace[1673458209] 'process raft request' (duration: 274.678781ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.737431Z","caller":"traceutil/trace.go:171","msg":"trace[763431129] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"271.711777ms","start":"2026-03-17T21:53:47.465709Z","end":"2026-03-17T21:53:47.737421Z","steps":["trace[763431129] 'process raft request' (duration: 271.317274ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.737547Z","caller":"traceutil/trace.go:171","msg":"trace[1168203887] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"274.127786ms","start":"2026-03-17T21:53:47.463405Z","end":"2026-03-17T21:53:47.737533Z","steps":["trace[1168203887] 'process raft request' (duration: 273.570052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:47.737869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.844987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-17T21:53:47.737918Z","caller":"traceutil/trace.go:171","msg":"trace[594730196] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:315; }","duration":"188.942177ms","start":"2026-03-17T21:53:47.548965Z","end":"2026-03-17T21:53:47.737907Z","steps":["trace[594730196] 'agreement among raft nodes before linearized reading' (duration: 188.850627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:47.738177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.74353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-17T21:53:47.738224Z","caller":"traceutil/trace.go:171","msg":"trace[1157926022] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:315; }","duration":"139.818521ms","start":"2026-03-17T21:53:47.598393Z","end":"2026-03-17T21:53:47.738212Z","steps":["trace[1157926022] 'agreement among raft nodes before linearized reading' (duration: 139.72742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:47.739449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.011651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-17T21:53:47.74052Z","caller":"traceutil/trace.go:171","msg":"trace[131504206] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:315; }","duration":"241.083349ms","start":"2026-03-17T21:53:47.499399Z","end":"2026-03-17T21:53:47.740483Z","steps":["trace[131504206] 'agreement among raft nodes before linearized reading' (duration: 238.064875ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.873886Z","caller":"traceutil/trace.go:171","msg":"trace[44115742] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"124.682358ms","start":"2026-03-17T21:53:47.74918Z","end":"2026-03-17T21:53:47.873862Z","steps":["trace[44115742] 'process raft request' (duration: 124.518686ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.873885Z","caller":"traceutil/trace.go:171","msg":"trace[1789899941] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"124.501387ms","start":"2026-03-17T21:53:47.749359Z","end":"2026-03-17T21:53:47.87386Z","steps":["trace[1789899941] 'process raft request' (duration: 124.375786ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.874054Z","caller":"traceutil/trace.go:171","msg":"trace[691393690] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"125.942498ms","start":"2026-03-17T21:53:47.748052Z","end":"2026-03-17T21:53:47.873994Z","steps":["trace[691393690] 'process raft request' (duration: 125.602665ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.873863Z","caller":"traceutil/trace.go:171","msg":"trace[1511526349] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"127.248508ms","start":"2026-03-17T21:53:47.746582Z","end":"2026-03-17T21:53:47.873831Z","steps":["trace[1511526349] 'process raft request' (duration: 93.239524ms)","trace[1511526349] 'compare' (duration: 33.657081ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:47.874422Z","caller":"traceutil/trace.go:171","msg":"trace[1240007556] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"124.97435ms","start":"2026-03-17T21:53:47.749435Z","end":"2026-03-17T21:53:47.874409Z","steps":["trace[1240007556] 'process raft request' (duration: 124.365165ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.874911Z","caller":"traceutil/trace.go:171","msg":"trace[99690198] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:327; }","duration":"125.828117ms","start":"2026-03-17T21:53:47.749055Z","end":"2026-03-17T21:53:47.874883Z","steps":["trace[99690198] 'read index received' (duration: 90.777354ms)","trace[99690198] 'applied index is now lower than readState.Index' (duration: 35.049733ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:47.874945Z","caller":"traceutil/trace.go:171","msg":"trace[1368421508] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"125.342873ms","start":"2026-03-17T21:53:47.749574Z","end":"2026-03-17T21:53:47.874917Z","steps":["trace[1368421508] 'process raft request' (duration: 124.613957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:47.875451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.388411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-03-17T21:53:47.87552Z","caller":"traceutil/trace.go:171","msg":"trace[1718695373] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:322; }","duration":"126.465022ms","start":"2026-03-17T21:53:47.74903Z","end":"2026-03-17T21:53:47.875495Z","steps":["trace[1718695373] 'agreement among raft nodes before linearized reading' (duration: 126.365011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:47.876035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.084969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-17T21:53:47.876095Z","caller":"traceutil/trace.go:171","msg":"trace[11957048] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:322; }","duration":"126.198721ms","start":"2026-03-17T21:53:47.749883Z","end":"2026-03-17T21:53:47.876082Z","steps":["trace[11957048] 'agreement among raft nodes before linearized reading' (duration: 126.0736ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:47.877208Z","caller":"traceutil/trace.go:171","msg":"trace[1698487812] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"124.129113ms","start":"2026-03-17T21:53:47.751078Z","end":"2026-03-17T21:53:47.875207Z","steps":["trace[1698487812] 'process raft request' (duration: 123.74945ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:48.150264Z","caller":"traceutil/trace.go:171","msg":"trace[1685380948] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"265.984269ms","start":"2026-03-17T21:53:47.884249Z","end":"2026-03-17T21:53:48.150233Z","steps":["trace[1685380948] 'process raft request' (duration: 265.859378ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:48.150291Z","caller":"traceutil/trace.go:171","msg":"trace[1443045595] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"267.952185ms","start":"2026-03-17T21:53:47.882293Z","end":"2026-03-17T21:53:48.150245Z","steps":["trace[1443045595] 'process raft request' (duration: 203.911533ms)","trace[1443045595] 'compare' (duration: 63.631609ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:48.150414Z","caller":"traceutil/trace.go:171","msg":"trace[1908294464] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:334; }","duration":"268.019396ms","start":"2026-03-17T21:53:47.882371Z","end":"2026-03-17T21:53:48.15039Z","steps":["trace[1908294464] 'read index received' (duration: 8.333517ms)","trace[1908294464] 'applied index is now lower than readState.Index' (duration: 259.684389ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:48.150567Z","caller":"traceutil/trace.go:171","msg":"trace[673461123] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"267.074038ms","start":"2026-03-17T21:53:47.883482Z","end":"2026-03-17T21:53:48.150556Z","steps":["trace[673461123] 'process raft request' (duration: 266.561654ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:48.150621Z","caller":"traceutil/trace.go:171","msg":"trace[1726385483] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"256.737435ms","start":"2026-03-17T21:53:47.893799Z","end":"2026-03-17T21:53:48.150536Z","steps":["trace[1726385483] 'process raft request' (duration: 256.357862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:48.150579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.299888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-03-17T21:53:48.150745Z","caller":"traceutil/trace.go:171","msg":"trace[2091532151] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:327; }","duration":"268.50302ms","start":"2026-03-17T21:53:47.882228Z","end":"2026-03-17T21:53:48.150731Z","steps":["trace[2091532151] 'agreement among raft nodes before linearized reading' (duration: 268.251128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:48.766575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.921931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:53:48.767027Z","caller":"traceutil/trace.go:171","msg":"trace[1184325439] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"610.324837ms","start":"2026-03-17T21:53:48.156665Z","end":"2026-03-17T21:53:48.76699Z","steps":["trace[1184325439] 'process raft request' (duration: 610.210986ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:48.767036Z","caller":"traceutil/trace.go:171","msg":"trace[1521926583] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"610.65448ms","start":"2026-03-17T21:53:48.156328Z","end":"2026-03-17T21:53:48.766983Z","steps":["trace[1521926583] 'process raft request' (duration: 424.839592ms)","trace[1521926583] 'compare' (duration: 184.74789ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:53:48.7673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.156305Z","time spent":"610.881652ms","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:53:48.767245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.156637Z","time spent":"610.469818ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:53:48.767359Z","caller":"traceutil/trace.go:171","msg":"trace[1314540582] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:339; }","duration":"295.363405ms","start":"2026-03-17T21:53:48.471978Z","end":"2026-03-17T21:53:48.767341Z","steps":["trace[1314540582] 'read index received' (duration: 109.232494ms)","trace[1314540582] 'applied index is now lower than readState.Index' (duration: 186.129971ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:48.767393Z","caller":"traceutil/trace.go:171","msg":"trace[103539798] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"610.089185ms","start":"2026-03-17T21:53:48.15727Z","end":"2026-03-17T21:53:48.767359Z","steps":["trace[103539798] 'process raft request' (duration: 609.887213ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:48.767409Z","caller":"traceutil/trace.go:171","msg":"trace[916675096] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"610.63999ms","start":"2026-03-17T21:53:48.15675Z","end":"2026-03-17T21:53:48.76739Z","steps":["trace[916675096] 'process raft request' (duration: 610.186417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:48.767497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.156744Z","time spent":"610.701611ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:53:48.767604Z","caller":"traceutil/trace.go:171","msg":"trace[1494885380] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"610.789051ms","start":"2026-03-17T21:53:48.156803Z","end":"2026-03-17T21:53:48.767592Z","steps":["trace[1494885380] 'process raft request' (duration: 610.173026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:48.76765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.156798Z","time spent":"610.826591ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:53:48.767648Z","caller":"traceutil/trace.go:171","msg":"trace[859973547] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"609.833283ms","start":"2026-03-17T21:53:48.157804Z","end":"2026-03-17T21:53:48.767637Z","steps":["trace[859973547] 'process raft request' (duration: 609.40418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:48.767835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.157781Z","time spent":"609.945084ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2824,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:48.767852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.860399ms","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-03-17T21:53:48.7679Z","caller":"traceutil/trace.go:171","msg":"trace[1759738603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:336; }","duration":"295.94437ms","start":"2026-03-17T21:53:48.471942Z","end":"2026-03-17T21:53:48.767886Z","steps":["trace[1759738603] 'agreement among raft nodes before linearized reading' (duration: 295.808819ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:48.768063Z","caller":"traceutil/trace.go:171","msg":"trace[1920905342] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"611.071984ms","start":"2026-03-17T21:53:48.156978Z","end":"2026-03-17T21:53:48.76805Z","steps":["trace[1920905342] 'process raft request' (duration: 610.036915ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:48.768118Z","caller":"traceutil/trace.go:171","msg":"trace[1615342366] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"603.974007ms","start":"2026-03-17T21:53:48.164125Z","end":"2026-03-17T21:53:48.768099Z","steps":["trace[1615342366] 'process raft request' (duration: 603.173881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:48.768142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.156972Z","time spent":"611.125464ms","remote":"127.0.0.1:50264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:53:48.768223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.164116Z","time spent":"604.051798ms","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:53:48.768354Z","caller":"traceutil/trace.go:171","msg":"trace[613862065] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"610.299967ms","start":"2026-03-17T21:53:48.158042Z","end":"2026-03-17T21:53:48.768342Z","steps":["trace[613862065] 'process raft request' (duration: 609.210838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:48.768445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.158033Z","time spent":"610.368077ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:48.770722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.157252Z","time spent":"610.191387ms","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:49.118457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.333743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:53:49.118611Z","caller":"traceutil/trace.go:171","msg":"trace[1202341906] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"345.860121ms","start":"2026-03-17T21:53:48.772727Z","end":"2026-03-17T21:53:49.118587Z","steps":["trace[1202341906] 'process raft request' (duration: 219.302666ms)","trace[1202341906] 'compare' (duration: 126.130231ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:53:49.118677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.7727Z","time spent":"345.954401ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:53:49.135738Z","caller":"traceutil/trace.go:171","msg":"trace[1714946351] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"361.504905ms","start":"2026-03-17T21:53:48.774191Z","end":"2026-03-17T21:53:49.135696Z","steps":["trace[1714946351] 'process raft request' (duration: 361.206942ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:49.135783Z","caller":"traceutil/trace.go:171","msg":"trace[1044616892] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"361.295583ms","start":"2026-03-17T21:53:48.774464Z","end":"2026-03-17T21:53:49.135759Z","steps":["trace[1044616892] 'process raft request' (duration: 361.111912ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:49.136001Z","caller":"traceutil/trace.go:171","msg":"trace[1810102000] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"360.256105ms","start":"2026-03-17T21:53:48.775729Z","end":"2026-03-17T21:53:49.135985Z","steps":["trace[1810102000] 'process raft request' (duration: 359.972363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:49.136015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.774455Z","time spent":"361.389734ms","remote":"127.0.0.1:50636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:49.136092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.77571Z","time spent":"360.339546ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:49.136085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.774157Z","time spent":"361.680757ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:53:49.136151Z","caller":"traceutil/trace.go:171","msg":"trace[1196989352] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"360.595898ms","start":"2026-03-17T21:53:48.775528Z","end":"2026-03-17T21:53:49.136124Z","steps":["trace[1196989352] 'process raft request' (duration: 360.114534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:49.136321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.7755Z","time spent":"360.728469ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:53:49.136386Z","caller":"traceutil/trace.go:171","msg":"trace[68991440] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"360.602348ms","start":"2026-03-17T21:53:48.775773Z","end":"2026-03-17T21:53:49.136375Z","steps":["trace[68991440] 'process raft request' (duration: 360.150745ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:49.136414Z","caller":"traceutil/trace.go:171","msg":"trace[659488278] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:348; }","duration":"361.980889ms","start":"2026-03-17T21:53:48.774414Z","end":"2026-03-17T21:53:49.136395Z","steps":["trace[659488278] 'read index received' (duration: 217.780965ms)","trace[659488278] 'applied index is now lower than readState.Index' (duration: 144.198594ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:53:49.136447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.775762Z","time spent":"360.654038ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:53:49.136466Z","caller":"traceutil/trace.go:171","msg":"trace[1861449225] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"358.574272ms","start":"2026-03-17T21:53:48.777878Z","end":"2026-03-17T21:53:49.136452Z","steps":["trace[1861449225] 'process raft request' (duration: 358.447451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:49.136534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.77786Z","time spent":"358.639542ms","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:49.136582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.160011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-x7v5h\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-03-17T21:53:49.136623Z","caller":"traceutil/trace.go:171","msg":"trace[1904348189] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-x7v5h; range_end:; response_count:1; response_revision:343; }","duration":"362.235081ms","start":"2026-03-17T21:53:48.774377Z","end":"2026-03-17T21:53:49.136612Z","steps":["trace[1904348189] 'agreement among raft nodes before linearized reading' (duration: 362.12963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:49.136653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.774365Z","time spent":"362.281601ms","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3462,"request content":"key:\"/registry/pods/kube-system/kube-proxy-x7v5h\" "} {"level":"warn","ts":"2026-03-17T21:53:49.136669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.603757ms","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-03-17T21:53:49.136676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.153746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-03-17T21:53:49.136702Z","caller":"traceutil/trace.go:171","msg":"trace[1402583623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:343; }","duration":"105.658699ms","start":"2026-03-17T21:53:49.031033Z","end":"2026-03-17T21:53:49.136691Z","steps":["trace[1402583623] 'agreement among raft nodes before linearized reading' (duration: 105.596638ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:49.136717Z","caller":"traceutil/trace.go:171","msg":"trace[2042742214] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:343; }","duration":"359.217337ms","start":"2026-03-17T21:53:48.777488Z","end":"2026-03-17T21:53:49.136705Z","steps":["trace[2042742214] 'agreement among raft nodes before linearized reading' (duration: 359.111296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:49.13675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:48.777481Z","time spent":"359.262487ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4040,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-03-17T21:53:49.1369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.562446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-17T21:53:49.13695Z","caller":"traceutil/trace.go:171","msg":"trace[1135648655] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:343; }","duration":"240.653017ms","start":"2026-03-17T21:53:48.896283Z","end":"2026-03-17T21:53:49.136936Z","steps":["trace[1135648655] 'agreement among raft nodes before linearized reading' (duration: 240.567526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:49.557327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.3428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:53:49.557858Z","caller":"traceutil/trace.go:171","msg":"trace[202906382] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"415.424505ms","start":"2026-03-17T21:53:49.142392Z","end":"2026-03-17T21:53:49.557817Z","steps":["trace[202906382] 'process raft request' (duration: 195.49406ms)","trace[202906382] 'compare' (duration: 219.079428ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:49.557949Z","caller":"traceutil/trace.go:171","msg":"trace[901412521] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:355; }","duration":"413.728902ms","start":"2026-03-17T21:53:49.144202Z","end":"2026-03-17T21:53:49.557931Z","steps":["trace[901412521] 'read index received' (duration: 193.647116ms)","trace[901412521] 'applied index is now lower than readState.Index' (duration: 220.080696ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:49.557944Z","caller":"traceutil/trace.go:171","msg":"trace[1380273004] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"415.380525ms","start":"2026-03-17T21:53:49.142505Z","end":"2026-03-17T21:53:49.557885Z","steps":["trace[1380273004] 'process raft request' (duration: 414.950381ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:49.558033Z","caller":"traceutil/trace.go:171","msg":"trace[1891281958] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"414.674729ms","start":"2026-03-17T21:53:49.143305Z","end":"2026-03-17T21:53:49.55798Z","steps":["trace[1891281958] 'process raft request' (duration: 414.237366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:49.558141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:49.142461Z","time spent":"415.590177ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:53:49.558217Z","caller":"traceutil/trace.go:171","msg":"trace[1824309681] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"412.624303ms","start":"2026-03-17T21:53:49.14558Z","end":"2026-03-17T21:53:49.558205Z","steps":["trace[1824309681] 'process raft request' (duration: 412.24501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:49.558289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:49.145567Z","time spent":"412.681383ms","remote":"127.0.0.1:50636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:49.558287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:49.143286Z","time spent":"414.859881ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:49.55832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.106805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-03-17T21:53:49.558345Z","caller":"traceutil/trace.go:171","msg":"trace[2016561803] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"411.744546ms","start":"2026-03-17T21:53:49.146565Z","end":"2026-03-17T21:53:49.558309Z","steps":["trace[2016561803] 'process raft request' (duration: 411.315813ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:49.558383Z","caller":"traceutil/trace.go:171","msg":"trace[1761969419] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:348; }","duration":"414.199775ms","start":"2026-03-17T21:53:49.144167Z","end":"2026-03-17T21:53:49.558367Z","steps":["trace[1761969419] 'agreement among raft nodes before linearized reading' (duration: 414.081084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:49.558071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:49.142368Z","time spent":"415.589676ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:49.558431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:49.144155Z","time spent":"414.268996ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2924,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-03-17T21:53:49.558528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:49.146556Z","time spent":"411.869737ms","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4451,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:53:51.847798Z","caller":"traceutil/trace.go:171","msg":"trace[327445773] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"200.055689ms","start":"2026-03-17T21:53:51.647701Z","end":"2026-03-17T21:53:51.847757Z","steps":["trace[327445773] 'process raft request' (duration: 198.022433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:52.443862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.349477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:53:52.443977Z","caller":"traceutil/trace.go:171","msg":"trace[538737113] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"258.387605ms","start":"2026-03-17T21:53:52.18557Z","end":"2026-03-17T21:53:52.443958Z","steps":["trace[538737113] 'process raft request' (duration: 136.846846ms)","trace[538737113] 'compare' (duration: 121.186776ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:52.491712Z","caller":"traceutil/trace.go:171","msg":"trace[1995716910] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"297.550957ms","start":"2026-03-17T21:53:52.194135Z","end":"2026-03-17T21:53:52.491686Z","steps":["trace[1995716910] 'process raft request' (duration: 297.485436ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:52.491801Z","caller":"traceutil/trace.go:171","msg":"trace[1141742364] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"303.670214ms","start":"2026-03-17T21:53:52.188075Z","end":"2026-03-17T21:53:52.491745Z","steps":["trace[1141742364] 'process raft request' (duration: 303.387692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:52.492174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:52.188061Z","time spent":"303.909536ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:52.870963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.127743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:53:52.871165Z","caller":"traceutil/trace.go:171","msg":"trace[1593851405] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"330.172509ms","start":"2026-03-17T21:53:52.540924Z","end":"2026-03-17T21:53:52.871096Z","steps":["trace[1593851405] 'process raft request' (duration: 141.794074ms)","trace[1593851405] 'compare' (duration: 187.882321ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:53:52.871437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:52.540895Z","time spent":"330.32193ms","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:53:53.467761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.228383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:53:53.467882Z","caller":"traceutil/trace.go:171","msg":"trace[1060600441] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"251.505855ms","start":"2026-03-17T21:53:53.216355Z","end":"2026-03-17T21:53:53.467861Z","steps":["trace[1060600441] 'read index received' (duration: 21.62µs)","trace[1060600441] 'applied index is now lower than readState.Index' (duration: 251.483035ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:53:53.467953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.592086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-17T21:53:53.467973Z","caller":"traceutil/trace.go:171","msg":"trace[638909004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:367; }","duration":"251.681556ms","start":"2026-03-17T21:53:53.216283Z","end":"2026-03-17T21:53:53.467965Z","steps":["trace[638909004] 'agreement among raft nodes before linearized reading' (duration: 251.627175ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:53:53.468002Z","caller":"traceutil/trace.go:171","msg":"trace[40582902] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"271.165905ms","start":"2026-03-17T21:53:53.196803Z","end":"2026-03-17T21:53:53.467969Z","steps":["trace[40582902] 'process raft request' (duration: 19.556929ms)","trace[40582902] 'compare' (duration: 251.072032ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:53.748601Z","caller":"traceutil/trace.go:171","msg":"trace[1838095227] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"274.936884ms","start":"2026-03-17T21:53:53.4736Z","end":"2026-03-17T21:53:53.748537Z","steps":["trace[1838095227] 'process raft request' (duration: 176.46126ms)","trace[1838095227] 'compare' (duration: 98.097771ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:53:54.105017Z","caller":"traceutil/trace.go:171","msg":"trace[250562047] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"222.196084ms","start":"2026-03-17T21:53:53.882786Z","end":"2026-03-17T21:53:54.104982Z","steps":["trace[250562047] 'process raft request' (duration: 221.923522ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:54:00.268554Z","caller":"traceutil/trace.go:171","msg":"trace[425167493] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"495.897694ms","start":"2026-03-17T21:53:59.772636Z","end":"2026-03-17T21:54:00.268534Z","steps":["trace[425167493] 'read index received' (duration: 495.651012ms)","trace[425167493] 'applied index is now lower than readState.Index' (duration: 245.842µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:54:00.268657Z","caller":"traceutil/trace.go:171","msg":"trace[2124295395] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"774.440387ms","start":"2026-03-17T21:53:59.494208Z","end":"2026-03-17T21:54:00.268649Z","steps":["trace[2124295395] 'process raft request' (duration: 774.134584ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:00.268761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:59.494193Z","time spent":"774.482527ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:54:00.268862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.05407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-17T21:54:00.268943Z","caller":"traceutil/trace.go:171","msg":"trace[1342921232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"487.297191ms","start":"2026-03-17T21:53:59.781628Z","end":"2026-03-17T21:54:00.268925Z","steps":["trace[1342921232] 'agreement among raft nodes before linearized reading' (duration: 487.133599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:00.268975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:59.781552Z","time spent":"487.414302ms","remote":"127.0.0.1:50444","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-03-17T21:54:00.269157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.202742ms","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-03-17T21:54:00.26918Z","caller":"traceutil/trace.go:171","msg":"trace[28299907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"479.295622ms","start":"2026-03-17T21:53:59.789876Z","end":"2026-03-17T21:54:00.269172Z","steps":["trace[28299907] 'agreement among raft nodes before linearized reading' (duration: 479.250392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:00.269199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:59.78986Z","time spent":"479.334484ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-17T21:54:00.269208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.575629ms","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-03-17T21:54:00.26924Z","caller":"traceutil/trace.go:171","msg":"trace[1189976108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"496.63659ms","start":"2026-03-17T21:53:59.772595Z","end":"2026-03-17T21:54:00.269232Z","steps":["trace[1189976108] 'agreement among raft nodes before linearized reading' (duration: 496.580679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:00.269255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:53:59.772579Z","time spent":"496.6723ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-17T21:54:00.89519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.429145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:54:00.895505Z","caller":"traceutil/trace.go:171","msg":"trace[987200069] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"620.287283ms","start":"2026-03-17T21:54:00.2752Z","end":"2026-03-17T21:54:00.895487Z","steps":["trace[987200069] 'process raft request' (duration: 620.238542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:00.895587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:00.275186Z","time spent":"620.365773ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:54:00.895737Z","caller":"traceutil/trace.go:171","msg":"trace[286193083] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"620.562245ms","start":"2026-03-17T21:54:00.27516Z","end":"2026-03-17T21:54:00.895723Z","steps":["trace[286193083] 'process raft request' (duration: 620.162062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:00.895805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:00.275115Z","time spent":"620.653136ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:54:00.895855Z","caller":"traceutil/trace.go:171","msg":"trace[321238056] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"621.599622ms","start":"2026-03-17T21:54:00.274216Z","end":"2026-03-17T21:54:00.895816Z","steps":["trace[321238056] 'process raft request' (duration: 494.399591ms)","trace[321238056] 'compare' (duration: 126.235094ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:54:00.896066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:00.274196Z","time spent":"621.770393ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:54:01.41737Z","caller":"traceutil/trace.go:171","msg":"trace[1247680644] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"394.016693ms","start":"2026-03-17T21:54:01.023319Z","end":"2026-03-17T21:54:01.417336Z","steps":["trace[1247680644] 'process raft request' (duration: 393.372738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:01.417779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:01.02329Z","time spent":"394.167644ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:54:10.535483Z","caller":"traceutil/trace.go:171","msg":"trace[848984279] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:456; }","duration":"141.567702ms","start":"2026-03-17T21:54:10.393896Z","end":"2026-03-17T21:54:10.535464Z","steps":["trace[848984279] 'read index received' (duration: 141.432812ms)","trace[848984279] 'applied index is now lower than readState.Index' (duration: 133.56µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:54:10.535604Z","caller":"traceutil/trace.go:171","msg":"trace[1932769464] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"156.05468ms","start":"2026-03-17T21:54:10.37949Z","end":"2026-03-17T21:54:10.535544Z","steps":["trace[1932769464] 'process raft request' (duration: 155.813578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:10.535645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.718463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:54:10.535765Z","caller":"traceutil/trace.go:171","msg":"trace[1337365676] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:439; }","duration":"141.913204ms","start":"2026-03-17T21:54:10.393832Z","end":"2026-03-17T21:54:10.535745Z","steps":["trace[1337365676] 'agreement among raft nodes before linearized reading' (duration: 141.724063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:12.669369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645130215087141,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-17T21:54:12.722223Z","caller":"traceutil/trace.go:171","msg":"trace[2121628292] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"553.568881ms","start":"2026-03-17T21:54:12.168629Z","end":"2026-03-17T21:54:12.722198Z","steps":["trace[2121628292] 'read index received' (duration: 553.4191ms)","trace[2121628292] 'applied index is now lower than readState.Index' (duration: 148.091µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:54:12.722369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:12.131721Z","time spent":"590.64552ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-17T21:54:12.72269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.046604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:54:12.722735Z","caller":"traceutil/trace.go:171","msg":"trace[1045419224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"554.148175ms","start":"2026-03-17T21:54:12.168574Z","end":"2026-03-17T21:54:12.722722Z","steps":["trace[1045419224] 'agreement among raft nodes before linearized reading' (duration: 554.059064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:12.722772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:12.168549Z","time spent":"554.216215ms","remote":"127.0.0.1:53116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-17T21:54:12.722971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.174274ms","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-03-17T21:54:12.723009Z","caller":"traceutil/trace.go:171","msg":"trace[679844724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:478; }","duration":"182.210045ms","start":"2026-03-17T21:54:12.540789Z","end":"2026-03-17T21:54:12.722999Z","steps":["trace[679844724] 'agreement among raft nodes before linearized reading' (duration: 182.141574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:12.986477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.860348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:54:12.986821Z","caller":"traceutil/trace.go:171","msg":"trace[249843469] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:497; }","duration":"234.608827ms","start":"2026-03-17T21:54:12.752182Z","end":"2026-03-17T21:54:12.986791Z","steps":["trace[249843469] 'read index received' (duration: 133.277365ms)","trace[249843469] 'applied index is now lower than readState.Index' (duration: 101.329792ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:54:12.98701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.828909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-17T21:54:12.987064Z","caller":"traceutil/trace.go:171","msg":"trace[1811751362] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:480; }","duration":"234.885419ms","start":"2026-03-17T21:54:12.752162Z","end":"2026-03-17T21:54:12.987047Z","steps":["trace[1811751362] 'agreement among raft nodes before linearized reading' (duration: 234.714158ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:54:12.987086Z","caller":"traceutil/trace.go:171","msg":"trace[1069211597] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"256.041971ms","start":"2026-03-17T21:54:12.731012Z","end":"2026-03-17T21:54:12.987054Z","steps":["trace[1069211597] 'process raft request' (duration: 255.631879ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:54:12.987177Z","caller":"traceutil/trace.go:171","msg":"trace[344414166] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"258.78077ms","start":"2026-03-17T21:54:12.728209Z","end":"2026-03-17T21:54:12.98699Z","steps":["trace[344414166] 'process raft request' (duration: 157.262128ms)","trace[344414166] 'compare' (duration: 100.648566ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:54:16.487228Z","caller":"traceutil/trace.go:171","msg":"trace[1898323357] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"300.678291ms","start":"2026-03-17T21:54:16.186506Z","end":"2026-03-17T21:54:16.487184Z","steps":["trace[1898323357] 'process raft request' (duration: 300.481069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:16.487516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:16.186484Z","time spent":"300.853062ms","remote":"127.0.0.1:50280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":389,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:54:19.580677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.946953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:54:19.580898Z","caller":"traceutil/trace.go:171","msg":"trace[988737377] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"444.055144ms","start":"2026-03-17T21:54:19.136819Z","end":"2026-03-17T21:54:19.580874Z","steps":["trace[988737377] 'process raft request' (duration: 190.734089ms)","trace[988737377] 'compare' (duration: 252.665521ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:54:19.581976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:19.136798Z","time spent":"444.99118ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:54:19.601143Z","caller":"traceutil/trace.go:171","msg":"trace[242963811] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:539; }","duration":"414.738694ms","start":"2026-03-17T21:54:19.186368Z","end":"2026-03-17T21:54:19.601106Z","steps":["trace[242963811] 'read index received' (duration: 141.203737ms)","trace[242963811] 'applied index is now lower than readState.Index' (duration: 273.533737ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:54:19.601181Z","caller":"traceutil/trace.go:171","msg":"trace[1972956741] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"462.339623ms","start":"2026-03-17T21:54:19.138805Z","end":"2026-03-17T21:54:19.601144Z","steps":["trace[1972956741] 'process raft request' (duration: 462.057551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:19.601372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:19.138782Z","time spent":"462.480024ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:54:19.601648Z","caller":"traceutil/trace.go:171","msg":"trace[1067980378] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"462.640375ms","start":"2026-03-17T21:54:19.138996Z","end":"2026-03-17T21:54:19.601636Z","steps":["trace[1067980378] 'process raft request' (duration: 462.061772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:19.601711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:19.138988Z","time spent":"462.692846ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:54:19.602054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.64146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5q478\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-17T21:54:19.60232Z","caller":"traceutil/trace.go:171","msg":"trace[408734229] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5q478; range_end:; response_count:1; response_revision:522; }","duration":"415.767191ms","start":"2026-03-17T21:54:19.186328Z","end":"2026-03-17T21:54:19.602095Z","steps":["trace[408734229] 'agreement among raft nodes before linearized reading' (duration: 415.409289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:19.602494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:19.186316Z","time spent":"416.163634ms","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-5q478\" "} {"level":"info","ts":"2026-03-17T21:54:19.726742Z","caller":"traceutil/trace.go:171","msg":"trace[1712251052] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"111.902397ms","start":"2026-03-17T21:54:19.614798Z","end":"2026-03-17T21:54:19.7267Z","steps":["trace[1712251052] 'process raft request' (duration: 81.61941ms)","trace[1712251052] 'compare' (duration: 30.062855ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:54:21.789592Z","caller":"traceutil/trace.go:171","msg":"trace[636976316] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"337.434564ms","start":"2026-03-17T21:54:21.452133Z","end":"2026-03-17T21:54:21.789568Z","steps":["trace[636976316] 'read index received' (duration: 337.304143ms)","trace[636976316] 'applied index is now lower than readState.Index' (duration: 129.281µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:54:21.789662Z","caller":"traceutil/trace.go:171","msg":"trace[74877457] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"662.085005ms","start":"2026-03-17T21:54:21.127521Z","end":"2026-03-17T21:54:21.789606Z","steps":["trace[74877457] 'process raft request' (duration: 661.906334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:21.789888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.600515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:54:21.789934Z","caller":"traceutil/trace.go:171","msg":"trace[1697585315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"337.824806ms","start":"2026-03-17T21:54:21.452098Z","end":"2026-03-17T21:54:21.789922Z","steps":["trace[1697585315] 'agreement among raft nodes before linearized reading' (duration: 337.609005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:21.789973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:21.452066Z","time spent":"337.895746ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-17T21:54:21.790098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.543752ms","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-03-17T21:54:21.790145Z","caller":"traceutil/trace.go:171","msg":"trace[1662565087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"183.645223ms","start":"2026-03-17T21:54:21.606487Z","end":"2026-03-17T21:54:21.790132Z","steps":["trace[1662565087] 'agreement among raft nodes before linearized reading' (duration: 183.411932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:21.790225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.888541ms","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-03-17T21:54:21.790181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.076028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-17T21:54:21.790309Z","caller":"traceutil/trace.go:171","msg":"trace[666397722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"185.049022ms","start":"2026-03-17T21:54:21.605238Z","end":"2026-03-17T21:54:21.790287Z","steps":["trace[666397722] 'agreement among raft nodes before linearized reading' (duration: 184.74803ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:54:21.790319Z","caller":"traceutil/trace.go:171","msg":"trace[1929378264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"203.343319ms","start":"2026-03-17T21:54:21.586956Z","end":"2026-03-17T21:54:21.7903Z","steps":["trace[1929378264] 'agreement among raft nodes before linearized reading' (duration: 202.892307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:54:21.790617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:54:21.1275Z","time spent":"662.256116ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:54:23.624186Z","caller":"traceutil/trace.go:171","msg":"trace[2010148385] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"271.859257ms","start":"2026-03-17T21:54:23.352295Z","end":"2026-03-17T21:54:23.624154Z","steps":["trace[2010148385] 'process raft request' (duration: 271.675406ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:19.636745Z","caller":"traceutil/trace.go:171","msg":"trace[1927145559] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"120.13395ms","start":"2026-03-17T21:55:19.516581Z","end":"2026-03-17T21:55:19.636715Z","steps":["trace[1927145559] 'process raft request' (duration: 119.973967ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:28.593798Z","caller":"traceutil/trace.go:171","msg":"trace[863689963] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"204.272213ms","start":"2026-03-17T21:55:28.389499Z","end":"2026-03-17T21:55:28.593771Z","steps":["trace[863689963] 'process raft request' (duration: 204.08384ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:28.671071Z","caller":"traceutil/trace.go:171","msg":"trace[1650831880] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:896; }","duration":"163.854168ms","start":"2026-03-17T21:55:28.507191Z","end":"2026-03-17T21:55:28.671045Z","steps":["trace[1650831880] 'read index received' (duration: 86.425288ms)","trace[1650831880] 'applied index is now lower than readState.Index' (duration: 77.42805ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:55:28.671094Z","caller":"traceutil/trace.go:171","msg":"trace[2016816203] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"280.132755ms","start":"2026-03-17T21:55:28.390943Z","end":"2026-03-17T21:55:28.671075Z","steps":["trace[2016816203] 'process raft request' (duration: 279.937891ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:28.671232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.009331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:55:28.671254Z","caller":"traceutil/trace.go:171","msg":"trace[60762382] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"280.253877ms","start":"2026-03-17T21:55:28.390991Z","end":"2026-03-17T21:55:28.671245Z","steps":["trace[60762382] 'process raft request' (duration: 280.013512ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:28.67127Z","caller":"traceutil/trace.go:171","msg":"trace[676239495] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:863; }","duration":"164.139183ms","start":"2026-03-17T21:55:28.507118Z","end":"2026-03-17T21:55:28.671257Z","steps":["trace[676239495] 'agreement among raft nodes before linearized reading' (duration: 164.050121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:43.099857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.023309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-730ea77f-7763-4ca3-9d88-6e205c12fd7f\" ","response":"range_response_count:1 size:3699"} {"level":"info","ts":"2026-03-17T21:55:43.099955Z","caller":"traceutil/trace.go:171","msg":"trace[1768010026] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-730ea77f-7763-4ca3-9d88-6e205c12fd7f; range_end:; response_count:1; response_revision:918; }","duration":"105.168282ms","start":"2026-03-17T21:55:42.994767Z","end":"2026-03-17T21:55:43.099935Z","steps":["trace[1768010026] 'range keys from in-memory index tree' (duration: 104.842487ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:44.795764Z","caller":"traceutil/trace.go:171","msg":"trace[1701747373] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"100.17216ms","start":"2026-03-17T21:55:44.695568Z","end":"2026-03-17T21:55:44.79574Z","steps":["trace[1701747373] 'read index received' (duration: 99.963347ms)","trace[1701747373] 'applied index is now lower than readState.Index' (duration: 207.863µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:55:44.795946Z","caller":"traceutil/trace.go:171","msg":"trace[669427267] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"101.855487ms","start":"2026-03-17T21:55:44.693962Z","end":"2026-03-17T21:55:44.795817Z","steps":["trace[669427267] 'process raft request' (duration: 101.630214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:44.796006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.411404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-730ea77f-7763-4ca3-9d88-6e205c12fd7f\" ","response":"range_response_count:1 size:2245"} {"level":"info","ts":"2026-03-17T21:55:44.796096Z","caller":"traceutil/trace.go:171","msg":"trace[295381335] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-730ea77f-7763-4ca3-9d88-6e205c12fd7f; range_end:; response_count:1; response_revision:923; }","duration":"100.545486ms","start":"2026-03-17T21:55:44.695525Z","end":"2026-03-17T21:55:44.796071Z","steps":["trace[295381335] 'agreement among raft nodes before linearized reading' (duration: 100.312473ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:47.087446Z","caller":"traceutil/trace.go:171","msg":"trace[1761039434] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"129.90307ms","start":"2026-03-17T21:55:46.957512Z","end":"2026-03-17T21:55:47.087415Z","steps":["trace[1761039434] 'process raft request' (duration: 129.730347ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:47.276555Z","caller":"traceutil/trace.go:171","msg":"trace[244740026] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"140.731831ms","start":"2026-03-17T21:55:47.135798Z","end":"2026-03-17T21:55:47.27653Z","steps":["trace[244740026] 'read index received' (duration: 130.076177ms)","trace[244740026] 'applied index is now lower than readState.Index' (duration: 10.654454ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:55:47.276656Z","caller":"traceutil/trace.go:171","msg":"trace[818054004] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"169.94661ms","start":"2026-03-17T21:55:47.10668Z","end":"2026-03-17T21:55:47.276626Z","steps":["trace[818054004] 'process raft request' (duration: 159.187025ms)","trace[818054004] 'compare' (duration: 10.561582ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:55:47.276782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.973355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-03-17T21:55:47.276826Z","caller":"traceutil/trace.go:171","msg":"trace[1417508014] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:944; }","duration":"141.076616ms","start":"2026-03-17T21:55:47.13574Z","end":"2026-03-17T21:55:47.276816Z","steps":["trace[1417508014] 'agreement among raft nodes before linearized reading' (duration: 140.923474ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:47.276924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.724905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:55:47.277008Z","caller":"traceutil/trace.go:171","msg":"trace[2068857546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"107.847376ms","start":"2026-03-17T21:55:47.169143Z","end":"2026-03-17T21:55:47.27699Z","steps":["trace[2068857546] 'agreement among raft nodes before linearized reading' (duration: 107.720814ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:50.031527Z","caller":"traceutil/trace.go:171","msg":"trace[1504402689] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"175.397535ms","start":"2026-03-17T21:55:49.856104Z","end":"2026-03-17T21:55:50.031501Z","steps":["trace[1504402689] 'process raft request' (duration: 175.238492ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:55.106125Z","caller":"traceutil/trace.go:171","msg":"trace[510831192] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"130.21438ms","start":"2026-03-17T21:55:54.975891Z","end":"2026-03-17T21:55:55.106105Z","steps":["trace[510831192] 'read index received' (duration: 130.050408ms)","trace[510831192] 'applied index is now lower than readState.Index' (duration: 163.232µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:55:55.106258Z","caller":"traceutil/trace.go:171","msg":"trace[1963939820] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"144.974346ms","start":"2026-03-17T21:55:54.961258Z","end":"2026-03-17T21:55:55.106233Z","steps":["trace[1963939820] 'process raft request' (duration: 144.727702ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:55.106477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.378902ms","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-03-17T21:55:55.106645Z","caller":"traceutil/trace.go:171","msg":"trace[1760162835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"130.761458ms","start":"2026-03-17T21:55:54.97586Z","end":"2026-03-17T21:55:55.106622Z","steps":["trace[1760162835] 'agreement among raft nodes before linearized reading' (duration: 130.331081ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:57.556316Z","caller":"traceutil/trace.go:171","msg":"trace[1479104504] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"339.731586ms","start":"2026-03-17T21:55:57.216557Z","end":"2026-03-17T21:55:57.556289Z","steps":["trace[1479104504] 'process raft request' (duration: 339.590454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:57.556462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:55:57.21653Z","time spent":"339.856609ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:55:57.575036Z","caller":"traceutil/trace.go:171","msg":"trace[762825145] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1022; }","duration":"117.379389ms","start":"2026-03-17T21:55:57.457633Z","end":"2026-03-17T21:55:57.575012Z","steps":["trace[762825145] 'read index received' (duration: 98.557118ms)","trace[762825145] 'applied index is now lower than readState.Index' (duration: 18.820991ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:55:57.575304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.626412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:55:57.575426Z","caller":"traceutil/trace.go:171","msg":"trace[723943206] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:981; }","duration":"117.813714ms","start":"2026-03-17T21:55:57.457585Z","end":"2026-03-17T21:55:57.575398Z","steps":["trace[723943206] 'agreement among raft nodes before linearized reading' (duration: 117.581471ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:21.572172Z","caller":"traceutil/trace.go:171","msg":"trace[1886549905] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"199.650514ms","start":"2026-03-17T21:56:21.372489Z","end":"2026-03-17T21:56:21.57214Z","steps":["trace[1886549905] 'read index received' (duration: 199.431361ms)","trace[1886549905] 'applied index is now lower than readState.Index' (duration: 217.623µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:56:21.572446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.652931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/default\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-03-17T21:56:21.572392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.883627ms","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-03-17T21:56:21.572491Z","caller":"traceutil/trace.go:171","msg":"trace[1022896513] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/default; range_end:; response_count:1; response_revision:1051; }","duration":"171.739062ms","start":"2026-03-17T21:56:21.40074Z","end":"2026-03-17T21:56:21.572479Z","steps":["trace[1022896513] 'agreement among raft nodes before linearized reading' (duration: 171.57772ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:21.57262Z","caller":"traceutil/trace.go:171","msg":"trace[828689371] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:1051; }","duration":"200.15466ms","start":"2026-03-17T21:56:21.372447Z","end":"2026-03-17T21:56:21.572602Z","steps":["trace[828689371] 'agreement among raft nodes before linearized reading' (duration: 199.842806ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:21.572341Z","caller":"traceutil/trace.go:171","msg":"trace[1741778263] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"246.882588ms","start":"2026-03-17T21:56:21.325407Z","end":"2026-03-17T21:56:21.57229Z","steps":["trace[1741778263] 'process raft request' (duration: 246.493533ms)"],"step_count":1}