{"level":"warn","ts":"2026-03-04T16:30:21.37455Z","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-04T16:30:21.37465Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-04T16:30:21.374722Z","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-04T16:30:21.374736Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-03-04T16:30:21.374756Z","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-04T16:30:21.375493Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-03-04T16:30:21.375573Z","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":16,"max-cpu-available":16,"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.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-04T16:30:21.382108Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.31923ms"} {"level":"info","ts":"2026-03-04T16:30:21.393008Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-03-04T16:30:21.393215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T16:30:21.393272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-03-04T16:30:21.393302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T16:30:21.393329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-03-04T16:30:21.393395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-03-04T16:30:21.402406Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T16:30:21.403685Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T16:30:21.404777Z","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-04T16:30:21.407414Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T16:30:21.407786Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T16:30:21.407778Z","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-04T16:30:21.407891Z","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-04T16:30:21.407914Z","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-04T16:30:21.408711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-03-04T16:30:21.409057Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-03-04T16:30:21.411937Z","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-04T16:30:21.412047Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-03-04T16:30:21.412065Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-03-04T16:30:21.412124Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T16:30:21.412161Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T16:30:21.995392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T16:30:21.99544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T16:30:21.995456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-03-04T16:30:21.995477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-03-04T16:30:21.995485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-03-04T16:30:21.995496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-03-04T16:30:21.995506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-03-04T16:30:21.997289Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T16:30:21.997632Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T16:30:21.998712Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T16:30:21.999166Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T16:30:21.999454Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T16:30:21.999482Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T16:30:21.999526Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T16:30:21.999617Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T16:30:21.999653Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T16:30:22.002289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-04T16:30:22.00498Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-03-04T16:30:28.014664Z","caller":"traceutil/trace.go:171","msg":"trace[32465333] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"163.777736ms","start":"2026-03-04T16:30:27.850871Z","end":"2026-03-04T16:30:28.014648Z","steps":["trace[32465333] 'read index received' (duration: 67.951364ms)","trace[32465333] 'applied index is now lower than readState.Index' (duration: 95.824232ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:28.014629Z","caller":"traceutil/trace.go:171","msg":"trace[786967898] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"163.506419ms","start":"2026-03-04T16:30:27.851098Z","end":"2026-03-04T16:30:28.014605Z","steps":["trace[786967898] 'process raft request' (duration: 163.456778ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:28.01478Z","caller":"traceutil/trace.go:171","msg":"trace[1778457179] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"176.209942ms","start":"2026-03-04T16:30:27.838563Z","end":"2026-03-04T16:30:28.014773Z","steps":["trace[1778457179] 'process raft request' (duration: 80.284937ms)","trace[1778457179] 'compare' (duration: 95.521494ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:30:28.015064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.174277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-03-04T16:30:28.015303Z","caller":"traceutil/trace.go:171","msg":"trace[2146411294] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:312; }","duration":"164.454526ms","start":"2026-03-04T16:30:27.850828Z","end":"2026-03-04T16:30:28.015283Z","steps":["trace[2146411294] 'agreement among raft nodes before linearized reading' (duration: 164.017023ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:28.21222Z","caller":"traceutil/trace.go:171","msg":"trace[1296119739] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"182.339438ms","start":"2026-03-04T16:30:28.029823Z","end":"2026-03-04T16:30:28.212162Z","steps":["trace[1296119739] 'process raft request' (duration: 94.724511ms)","trace[1296119739] 'compare' (duration: 87.378891ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:30:28.212345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.97878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-03-04T16:30:28.21242Z","caller":"traceutil/trace.go:171","msg":"trace[1588163501] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:316; }","duration":"123.094013ms","start":"2026-03-04T16:30:28.089309Z","end":"2026-03-04T16:30:28.212403Z","steps":["trace[1588163501] 'agreement among raft nodes before linearized reading' (duration: 122.932059ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:28.212083Z","caller":"traceutil/trace.go:171","msg":"trace[624011518] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:328; }","duration":"122.706422ms","start":"2026-03-04T16:30:28.08935Z","end":"2026-03-04T16:30:28.212057Z","steps":["trace[624011518] 'read index received' (duration: 35.138887ms)","trace[624011518] 'applied index is now lower than readState.Index' (duration: 87.566165ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:30:28.454594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.37779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-04T16:30:28.454743Z","caller":"traceutil/trace.go:171","msg":"trace[952641867] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"153.208344ms","start":"2026-03-04T16:30:28.301481Z","end":"2026-03-04T16:30:28.454689Z","steps":["trace[952641867] 'process raft request' (duration: 43.240167ms)","trace[952641867] 'compare' (duration: 109.216306ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:30:31.309506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.438093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-04T16:30:31.309638Z","caller":"traceutil/trace.go:171","msg":"trace[1571943327] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:341; }","duration":"123.583808ms","start":"2026-03-04T16:30:31.186027Z","end":"2026-03-04T16:30:31.309611Z","steps":["trace[1571943327] 'range keys from in-memory index tree' (duration: 123.283888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:31.752737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.498246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T16:30:31.752849Z","caller":"traceutil/trace.go:171","msg":"trace[764759048] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"216.190507ms","start":"2026-03-04T16:30:31.536641Z","end":"2026-03-04T16:30:31.752832Z","steps":["trace[764759048] 'read index received' (duration: 40.631µs)","trace[764759048] 'applied index is now lower than readState.Index' (duration: 216.148676ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:31.75291Z","caller":"traceutil/trace.go:171","msg":"trace[384716080] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"255.624385ms","start":"2026-03-04T16:30:31.497256Z","end":"2026-03-04T16:30:31.75288Z","steps":["trace[384716080] 'process raft request' (duration: 23.727348ms)","trace[384716080] 'compare' (duration: 231.368122ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:30:31.752975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.922978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-03-04T16:30:31.752968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.31334ms","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-04T16:30:31.753008Z","caller":"traceutil/trace.go:171","msg":"trace[52810186] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:344; }","duration":"165.99432ms","start":"2026-03-04T16:30:31.587003Z","end":"2026-03-04T16:30:31.752998Z","steps":["trace[52810186] 'agreement among raft nodes before linearized reading' (duration: 165.923618ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:31.753052Z","caller":"traceutil/trace.go:171","msg":"trace[1983532366] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:344; }","duration":"216.437924ms","start":"2026-03-04T16:30:31.536596Z","end":"2026-03-04T16:30:31.753034Z","steps":["trace[1983532366] 'agreement among raft nodes before linearized reading' (duration: 216.289359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:31.753106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.174475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-04T16:30:31.753158Z","caller":"traceutil/trace.go:171","msg":"trace[1564208383] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:344; }","duration":"116.272548ms","start":"2026-03-04T16:30:31.636873Z","end":"2026-03-04T16:30:31.753145Z","steps":["trace[1564208383] 'agreement among raft nodes before linearized reading' (duration: 116.159525ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:32.183475Z","caller":"traceutil/trace.go:171","msg":"trace[452674062] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"395.721654ms","start":"2026-03-04T16:30:31.787724Z","end":"2026-03-04T16:30:32.183446Z","steps":["trace[452674062] 'read index received' (duration: 338.707233ms)","trace[452674062] 'applied index is now lower than readState.Index' (duration: 57.013381ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:32.183593Z","caller":"traceutil/trace.go:171","msg":"trace[341956448] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"424.005064ms","start":"2026-03-04T16:30:31.759546Z","end":"2026-03-04T16:30:32.183551Z","steps":["trace[341956448] 'process raft request' (duration: 366.872339ms)","trace[341956448] 'compare' (duration: 56.870327ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:30:32.18369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.956791ms","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-04T16:30:32.183739Z","caller":"traceutil/trace.go:171","msg":"trace[1805527274] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:345; }","duration":"396.059425ms","start":"2026-03-04T16:30:31.787666Z","end":"2026-03-04T16:30:32.183725Z","steps":["trace[1805527274] 'agreement among raft nodes before linearized reading' (duration: 395.956412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:32.183801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.58473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-03-04T16:30:32.18378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:31.78765Z","time spent":"396.120417ms","remote":"127.0.0.1:42868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-03-04T16:30:32.18385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.985529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-04T16:30:32.183894Z","caller":"traceutil/trace.go:171","msg":"trace[1222808144] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:345; }","duration":"345.706464ms","start":"2026-03-04T16:30:31.838169Z","end":"2026-03-04T16:30:32.183875Z","steps":["trace[1222808144] 'agreement among raft nodes before linearized reading' (duration: 345.543609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:32.183937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.652488ms","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":"warn","ts":"2026-03-04T16:30:32.183946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.520083ms","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-04T16:30:32.183965Z","caller":"traceutil/trace.go:171","msg":"trace[493326460] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:345; }","duration":"196.70351ms","start":"2026-03-04T16:30:31.987254Z","end":"2026-03-04T16:30:32.183957Z","steps":["trace[493326460] 'agreement among raft nodes before linearized reading' (duration: 196.662509ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:32.184011Z","caller":"traceutil/trace.go:171","msg":"trace[305001193] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:345; }","duration":"146.604135ms","start":"2026-03-04T16:30:32.037375Z","end":"2026-03-04T16:30:32.183979Z","steps":["trace[305001193] 'agreement among raft nodes before linearized reading' (duration: 146.39961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:32.184055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.659921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-04T16:30:32.1841Z","caller":"traceutil/trace.go:171","msg":"trace[534411124] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:345; }","duration":"295.729173ms","start":"2026-03-04T16:30:31.888356Z","end":"2026-03-04T16:30:32.184085Z","steps":["trace[534411124] 'agreement among raft nodes before linearized reading' (duration: 295.635781ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:32.183901Z","caller":"traceutil/trace.go:171","msg":"trace[1737002089] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:345; }","duration":"246.076462ms","start":"2026-03-04T16:30:31.937812Z","end":"2026-03-04T16:30:32.183888Z","steps":["trace[1737002089] 'agreement among raft nodes before linearized reading' (duration: 245.975719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:32.183952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:31.838154Z","time spent":"345.788656ms","remote":"127.0.0.1:42868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-03-04T16:30:32.18444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:31.759526Z","time spent":"424.143589ms","remote":"127.0.0.1:42844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T16:30:33.191892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.029061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T16:30:33.192047Z","caller":"traceutil/trace.go:171","msg":"trace[158973940] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"1.002038635s","start":"2026-03-04T16:30:32.189962Z","end":"2026-03-04T16:30:33.192Z","steps":["trace[158973940] 'process raft request' (duration: 350.796958ms)","trace[158973940] 'compare' (duration: 650.065274ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:33.192114Z","caller":"traceutil/trace.go:171","msg":"trace[1725166368] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:358; }","duration":"1.002064707s","start":"2026-03-04T16:30:32.18999Z","end":"2026-03-04T16:30:33.192055Z","steps":["trace[1725166368] 'read index received' (duration: 73.212µs)","trace[1725166368] 'applied index is now lower than readState.Index' (duration: 1.001987254s)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:33.192189Z","caller":"traceutil/trace.go:171","msg":"trace[928682802] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"1.002145299s","start":"2026-03-04T16:30:32.190001Z","end":"2026-03-04T16:30:33.192146Z","steps":["trace[928682802] 'process raft request' (duration: 1.002007274s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.192251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.189941Z","time spent":"1.002204291s","remote":"127.0.0.1:42932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T16:30:33.192268Z","caller":"traceutil/trace.go:171","msg":"trace[472267806] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"1.000538104s","start":"2026-03-04T16:30:32.191678Z","end":"2026-03-04T16:30:33.192216Z","steps":["trace[472267806] 'process raft request' (duration: 1.000416861s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.192387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002384426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-03-04T16:30:33.192385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.189974Z","time spent":"1.002322964s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T16:30:33.192404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.191667Z","time spent":"1.000679068s","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T16:30:33.192422Z","caller":"traceutil/trace.go:171","msg":"trace[854000657] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"999.581136ms","start":"2026-03-04T16:30:32.192823Z","end":"2026-03-04T16:30:33.192404Z","steps":["trace[854000657] 'process raft request' (duration: 999.424971ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.192461Z","caller":"traceutil/trace.go:171","msg":"trace[1200784439] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:351; }","duration":"1.002473728s","start":"2026-03-04T16:30:32.189963Z","end":"2026-03-04T16:30:33.192437Z","steps":["trace[1200784439] 'agreement among raft nodes before linearized reading' (duration: 1.002295823s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.192506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.192812Z","time spent":"999.653138ms","remote":"127.0.0.1:43150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T16:30:33.192515Z","caller":"traceutil/trace.go:171","msg":"trace[1061228693] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"1.000595375s","start":"2026-03-04T16:30:32.191909Z","end":"2026-03-04T16:30:33.192504Z","steps":["trace[1061228693] 'process raft request' (duration: 1.000230875s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.192683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.191897Z","time spent":"1.000703398s","remote":"127.0.0.1:42868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T16:30:33.19252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.189951Z","time spent":"1.00255599s","remote":"127.0.0.1:42868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-03-04T16:30:33.192769Z","caller":"traceutil/trace.go:171","msg":"trace[1433738739] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"999.73933ms","start":"2026-03-04T16:30:32.193013Z","end":"2026-03-04T16:30:33.192752Z","steps":["trace[1433738739] 'process raft request' (duration: 999.274497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.192949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.193004Z","time spent":"999.903725ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T16:30:33.192976Z","caller":"traceutil/trace.go:171","msg":"trace[1997067664] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"998.556827ms","start":"2026-03-04T16:30:32.194399Z","end":"2026-03-04T16:30:33.192956Z","steps":["trace[1997067664] 'process raft request' (duration: 998.353161ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.192422Z","caller":"traceutil/trace.go:171","msg":"trace[1360335756] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"1.000165652s","start":"2026-03-04T16:30:32.19219Z","end":"2026-03-04T16:30:33.192356Z","steps":["trace[1360335756] 'process raft request' (duration: 1.000004928s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.193033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.400872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-03-04T16:30:33.193043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.238578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-04T16:30:33.192956Z","caller":"traceutil/trace.go:171","msg":"trace[36699642] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"999.234016ms","start":"2026-03-04T16:30:32.193683Z","end":"2026-03-04T16:30:33.192917Z","steps":["trace[36699642] 'process raft request' (duration: 998.943368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.193078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.194388Z","time spent":"998.633769ms","remote":"127.0.0.1:43134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T16:30:33.193127Z","caller":"traceutil/trace.go:171","msg":"trace[2108906263] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:354; }","duration":"955.557676ms","start":"2026-03-04T16:30:32.237516Z","end":"2026-03-04T16:30:33.193074Z","steps":["trace[2108906263] 'agreement among raft nodes before linearized reading' (duration: 955.3715ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.193099Z","caller":"traceutil/trace.go:171","msg":"trace[1062068425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:354; }","duration":"122.365752ms","start":"2026-03-04T16:30:33.070719Z","end":"2026-03-04T16:30:33.193085Z","steps":["trace[1062068425] 'agreement among raft nodes before linearized reading' (duration: 122.269239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.193173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.23745Z","time spent":"955.71255ms","remote":"127.0.0.1:42868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-03-04T16:30:33.193212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.192178Z","time spent":"1.000880633s","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T16:30:33.193958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:32.193671Z","time spent":"999.598896ms","remote":"127.0.0.1:42922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T16:30:33.378895Z","caller":"traceutil/trace.go:171","msg":"trace[1785336432] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"107.618425ms","start":"2026-03-04T16:30:33.271242Z","end":"2026-03-04T16:30:33.378861Z","steps":["trace[1785336432] 'read index received' (duration: 80.255306ms)","trace[1785336432] 'applied index is now lower than readState.Index' (duration: 27.361359ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:33.378979Z","caller":"traceutil/trace.go:171","msg":"trace[1839508105] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"146.773494ms","start":"2026-03-04T16:30:33.232166Z","end":"2026-03-04T16:30:33.37894Z","steps":["trace[1839508105] 'process raft request' (duration: 119.22561ms)","trace[1839508105] 'compare' (duration: 27.292367ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:30:33.379161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.889613ms","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-04T16:30:33.379234Z","caller":"traceutil/trace.go:171","msg":"trace[1220951048] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:366; }","duration":"108.035987ms","start":"2026-03-04T16:30:33.271179Z","end":"2026-03-04T16:30:33.379215Z","steps":["trace[1220951048] 'agreement among raft nodes before linearized reading' (duration: 107.853272ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.453078Z","caller":"traceutil/trace.go:171","msg":"trace[2092710275] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"180.16035ms","start":"2026-03-04T16:30:33.272887Z","end":"2026-03-04T16:30:33.453047Z","steps":["trace[2092710275] 'process raft request' (duration: 179.979995ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.453122Z","caller":"traceutil/trace.go:171","msg":"trace[1028130730] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"177.196254ms","start":"2026-03-04T16:30:33.275903Z","end":"2026-03-04T16:30:33.453099Z","steps":["trace[1028130730] 'process raft request' (duration: 177.030319ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.453098Z","caller":"traceutil/trace.go:171","msg":"trace[1081707885] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"181.560189ms","start":"2026-03-04T16:30:33.271506Z","end":"2026-03-04T16:30:33.453066Z","steps":["trace[1081707885] 'process raft request' (duration: 181.215249ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.453222Z","caller":"traceutil/trace.go:171","msg":"trace[456267131] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"171.447457ms","start":"2026-03-04T16:30:33.281756Z","end":"2026-03-04T16:30:33.453204Z","steps":["trace[456267131] 'process raft request' (duration: 171.220461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.453486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.862866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-03-04T16:30:33.453527Z","caller":"traceutil/trace.go:171","msg":"trace[2131413228] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:372; }","duration":"173.956209ms","start":"2026-03-04T16:30:33.279561Z","end":"2026-03-04T16:30:33.453517Z","steps":["trace[2131413228] 'agreement among raft nodes before linearized reading' (duration: 173.864306ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.45358Z","caller":"traceutil/trace.go:171","msg":"trace[1489682592] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"169.80365ms","start":"2026-03-04T16:30:33.283757Z","end":"2026-03-04T16:30:33.45356Z","steps":["trace[1489682592] 'process raft request' (duration: 169.396789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.45364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.674834ms","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-04T16:30:33.453706Z","caller":"traceutil/trace.go:171","msg":"trace[864703206] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:372; }","duration":"171.781997ms","start":"2026-03-04T16:30:33.281905Z","end":"2026-03-04T16:30:33.453687Z","steps":["trace[864703206] 'agreement among raft nodes before linearized reading' (duration: 171.606712ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.454007Z","caller":"traceutil/trace.go:171","msg":"trace[1612516203] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"164.357834ms","start":"2026-03-04T16:30:33.289631Z","end":"2026-03-04T16:30:33.453988Z","steps":["trace[1612516203] 'process raft request' (duration: 163.748946ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.614355Z","caller":"traceutil/trace.go:171","msg":"trace[1157000521] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"224.334732ms","start":"2026-03-04T16:30:33.389991Z","end":"2026-03-04T16:30:33.614326Z","steps":["trace[1157000521] 'process raft request' (duration: 224.267151ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.614457Z","caller":"traceutil/trace.go:171","msg":"trace[1796961412] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"230.924183ms","start":"2026-03-04T16:30:33.383461Z","end":"2026-03-04T16:30:33.614385Z","steps":["trace[1796961412] 'process raft request' (duration: 197.793457ms)","trace[1796961412] 'compare' (duration: 32.803317ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:33.672178Z","caller":"traceutil/trace.go:171","msg":"trace[260264711] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"214.391257ms","start":"2026-03-04T16:30:33.457743Z","end":"2026-03-04T16:30:33.672134Z","steps":["trace[260264711] 'process raft request' (duration: 214.14056ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.672277Z","caller":"traceutil/trace.go:171","msg":"trace[1830194184] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"214.386726ms","start":"2026-03-04T16:30:33.457849Z","end":"2026-03-04T16:30:33.672236Z","steps":["trace[1830194184] 'process raft request' (duration: 214.17729ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.672313Z","caller":"traceutil/trace.go:171","msg":"trace[1684584436] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:385; }","duration":"214.237032ms","start":"2026-03-04T16:30:33.458047Z","end":"2026-03-04T16:30:33.672284Z","steps":["trace[1684584436] 'read index received' (duration: 123.203225ms)","trace[1684584436] 'applied index is now lower than readState.Index' (duration: 91.032587ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:30:33.672494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.430447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-qnr89\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-03-04T16:30:33.672552Z","caller":"traceutil/trace.go:171","msg":"trace[1190370698] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"211.381319ms","start":"2026-03-04T16:30:33.461107Z","end":"2026-03-04T16:30:33.672488Z","steps":["trace[1190370698] 'process raft request' (duration: 210.971877ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.672564Z","caller":"traceutil/trace.go:171","msg":"trace[694569735] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"209.837695ms","start":"2026-03-04T16:30:33.462638Z","end":"2026-03-04T16:30:33.672476Z","steps":["trace[694569735] 'process raft request' (duration: 209.556097ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.672591Z","caller":"traceutil/trace.go:171","msg":"trace[41541467] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-qnr89; range_end:; response_count:1; response_revision:378; }","duration":"214.606103ms","start":"2026-03-04T16:30:33.457973Z","end":"2026-03-04T16:30:33.672579Z","steps":["trace[41541467] 'agreement among raft nodes before linearized reading' (duration: 214.441588ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.809938Z","caller":"traceutil/trace.go:171","msg":"trace[1268435716] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"191.154056ms","start":"2026-03-04T16:30:33.618756Z","end":"2026-03-04T16:30:33.80991Z","steps":["trace[1268435716] 'process raft request' (duration: 141.176073ms)","trace[1268435716] 'compare' (duration: 49.662034ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:33.810098Z","caller":"traceutil/trace.go:171","msg":"trace[657064049] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:391; }","duration":"137.687293ms","start":"2026-03-04T16:30:33.672385Z","end":"2026-03-04T16:30:33.810072Z","steps":["trace[657064049] 'read index received' (duration: 87.543526ms)","trace[657064049] 'applied index is now lower than readState.Index' (duration: 50.142337ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:33.810127Z","caller":"traceutil/trace.go:171","msg":"trace[522372409] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"188.583931ms","start":"2026-03-04T16:30:33.621509Z","end":"2026-03-04T16:30:33.810093Z","steps":["trace[522372409] 'process raft request' (duration: 188.286962ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.810221Z","caller":"traceutil/trace.go:171","msg":"trace[353766763] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"179.118868ms","start":"2026-03-04T16:30:33.631091Z","end":"2026-03-04T16:30:33.81021Z","steps":["trace[353766763] 'process raft request' (duration: 178.920053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.810312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.96044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-03-04T16:30:33.810341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.519323ms","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-04T16:30:33.810401Z","caller":"traceutil/trace.go:171","msg":"trace[2051073069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:381; }","duration":"316.636287ms","start":"2026-03-04T16:30:33.493748Z","end":"2026-03-04T16:30:33.810384Z","steps":["trace[2051073069] 'agreement among raft nodes before linearized reading' (duration: 316.527364ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.810384Z","caller":"traceutil/trace.go:171","msg":"trace[1346157030] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:381; }","duration":"208.092535ms","start":"2026-03-04T16:30:33.602274Z","end":"2026-03-04T16:30:33.810367Z","steps":["trace[1346157030] 'agreement among raft nodes before linearized reading' (duration: 207.963301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.810504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:30:33.493719Z","time spent":"316.7265ms","remote":"127.0.0.1:42932","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":"info","ts":"2026-03-04T16:30:33.820663Z","caller":"traceutil/trace.go:171","msg":"trace[292012128] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"143.389318ms","start":"2026-03-04T16:30:33.677261Z","end":"2026-03-04T16:30:33.82065Z","steps":["trace[292012128] 'process raft request' (duration: 143.139331ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.820712Z","caller":"traceutil/trace.go:171","msg":"trace[1845852816] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"139.518226ms","start":"2026-03-04T16:30:33.681176Z","end":"2026-03-04T16:30:33.820695Z","steps":["trace[1845852816] 'process raft request' (duration: 139.383612ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:33.820869Z","caller":"traceutil/trace.go:171","msg":"trace[1781799247] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"122.609569ms","start":"2026-03-04T16:30:33.698249Z","end":"2026-03-04T16:30:33.820859Z","steps":["trace[1781799247] 'process raft request' (duration: 122.366342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:33.820952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.006799ms","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-04T16:30:33.820987Z","caller":"traceutil/trace.go:171","msg":"trace[486570130] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:384; }","duration":"141.089281ms","start":"2026-03-04T16:30:33.67989Z","end":"2026-03-04T16:30:33.820979Z","steps":["trace[486570130] 'agreement among raft nodes before linearized reading' (duration: 141.007849ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:34.029335Z","caller":"traceutil/trace.go:171","msg":"trace[1917378020] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:401; }","duration":"123.851433ms","start":"2026-03-04T16:30:33.905452Z","end":"2026-03-04T16:30:34.029304Z","steps":["trace[1917378020] 'read index received' (duration: 45.251445ms)","trace[1917378020] 'applied index is now lower than readState.Index' (duration: 78.598358ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:30:34.029562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.543595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-03-04T16:30:34.029608Z","caller":"traceutil/trace.go:171","msg":"trace[289502441] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:390; }","duration":"175.667398ms","start":"2026-03-04T16:30:33.853925Z","end":"2026-03-04T16:30:34.029593Z","steps":["trace[289502441] 'agreement among raft nodes before linearized reading' (duration: 175.509494ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:34.029664Z","caller":"traceutil/trace.go:171","msg":"trace[2062620695] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"203.974195ms","start":"2026-03-04T16:30:33.82563Z","end":"2026-03-04T16:30:34.029605Z","steps":["trace[2062620695] 'process raft request' (duration: 124.993436ms)","trace[2062620695] 'compare' (duration: 78.425623ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:34.02986Z","caller":"traceutil/trace.go:171","msg":"trace[488353396] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"204.003117ms","start":"2026-03-04T16:30:33.825811Z","end":"2026-03-04T16:30:34.029814Z","steps":["trace[488353396] 'process raft request' (duration: 203.424529ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:34.032408Z","caller":"traceutil/trace.go:171","msg":"trace[1762919630] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"116.931324ms","start":"2026-03-04T16:30:33.915449Z","end":"2026-03-04T16:30:34.03238Z","steps":["trace[1762919630] 'process raft request' (duration: 116.762949ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:34.274079Z","caller":"traceutil/trace.go:171","msg":"trace[2032684868] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"236.627199ms","start":"2026-03-04T16:30:34.037419Z","end":"2026-03-04T16:30:34.274046Z","steps":["trace[2032684868] 'process raft request' (duration: 195.012128ms)","trace[2032684868] 'compare' (duration: 41.443356ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:34.291537Z","caller":"traceutil/trace.go:171","msg":"trace[622753969] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"249.817338ms","start":"2026-03-04T16:30:34.04169Z","end":"2026-03-04T16:30:34.291507Z","steps":["trace[622753969] 'process raft request' (duration: 249.558381ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:35.416391Z","caller":"traceutil/trace.go:171","msg":"trace[2134579336] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"127.505279ms","start":"2026-03-04T16:30:35.28886Z","end":"2026-03-04T16:30:35.416365Z","steps":["trace[2134579336] 'process raft request' (duration: 98.819131ms)","trace[2134579336] 'compare' (duration: 28.492072ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:35.416677Z","caller":"traceutil/trace.go:171","msg":"trace[1258203715] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"120.764785ms","start":"2026-03-04T16:30:35.295863Z","end":"2026-03-04T16:30:35.416628Z","steps":["trace[1258203715] 'process raft request' (duration: 120.457785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:36.181676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.056192ms","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-04T16:30:36.181768Z","caller":"traceutil/trace.go:171","msg":"trace[734759218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:404; }","duration":"148.202516ms","start":"2026-03-04T16:30:36.033544Z","end":"2026-03-04T16:30:36.181747Z","steps":["trace[734759218] 'range keys from in-memory index tree' (duration: 147.904807ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:42.18424Z","caller":"traceutil/trace.go:171","msg":"trace[1707033187] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"128.039253ms","start":"2026-03-04T16:30:42.056179Z","end":"2026-03-04T16:30:42.184218Z","steps":["trace[1707033187] 'read index received' (duration: 127.93641ms)","trace[1707033187] 'applied index is now lower than readState.Index' (duration: 101.793µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:30:42.184384Z","caller":"traceutil/trace.go:171","msg":"trace[1934477768] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"199.493657ms","start":"2026-03-04T16:30:41.984852Z","end":"2026-03-04T16:30:42.184346Z","steps":["trace[1934477768] 'process raft request' (duration: 199.222049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:30:42.184616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.199188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T16:30:42.184657Z","caller":"traceutil/trace.go:171","msg":"trace[1605834597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"128.54072ms","start":"2026-03-04T16:30:42.056106Z","end":"2026-03-04T16:30:42.184647Z","steps":["trace[1605834597] 'agreement among raft nodes before linearized reading' (duration: 128.203199ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:47.536193Z","caller":"traceutil/trace.go:171","msg":"trace[336197780] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"108.993465ms","start":"2026-03-04T16:30:47.427179Z","end":"2026-03-04T16:30:47.536173Z","steps":["trace[336197780] 'process raft request' (duration: 108.876622ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:30:58.479636Z","caller":"traceutil/trace.go:171","msg":"trace[2048840760] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"107.987566ms","start":"2026-03-04T16:30:58.371614Z","end":"2026-03-04T16:30:58.479602Z","steps":["trace[2048840760] 'process raft request' (duration: 107.807611ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:31:05.950484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.842022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2w46n\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-04T16:31:05.950558Z","caller":"traceutil/trace.go:171","msg":"trace[968733520] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2w46n; range_end:; response_count:1; response_revision:566; }","duration":"168.967135ms","start":"2026-03-04T16:31:05.781574Z","end":"2026-03-04T16:31:05.950541Z","steps":["trace[968733520] 'range keys from in-memory index tree' (duration: 168.674487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:31:05.950666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.017474ms","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-04T16:31:05.95071Z","caller":"traceutil/trace.go:171","msg":"trace[936313025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"190.095405ms","start":"2026-03-04T16:31:05.760603Z","end":"2026-03-04T16:31:05.950699Z","steps":["trace[936313025] 'range keys from in-memory index tree' (duration: 189.877159ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:31:06.277046Z","caller":"traceutil/trace.go:171","msg":"trace[1765525650] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"314.294529ms","start":"2026-03-04T16:31:05.96273Z","end":"2026-03-04T16:31:06.277025Z","steps":["trace[1765525650] 'process raft request' (duration: 314.128184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:31:06.277171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:31:05.962698Z","time spent":"314.410372ms","remote":"127.0.0.1:42844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T16:31:26.051479Z","caller":"traceutil/trace.go:171","msg":"trace[2051407248] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"114.907906ms","start":"2026-03-04T16:31:25.936536Z","end":"2026-03-04T16:31:26.051444Z","steps":["trace[2051407248] 'process raft request' (duration: 114.742031ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:31:26.081613Z","caller":"traceutil/trace.go:171","msg":"trace[963510649] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"117.49761ms","start":"2026-03-04T16:31:25.964088Z","end":"2026-03-04T16:31:26.081586Z","steps":["trace[963510649] 'process raft request' (duration: 117.275644ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:31:26.081896Z","caller":"traceutil/trace.go:171","msg":"trace[229127795] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"117.659425ms","start":"2026-03-04T16:31:25.964188Z","end":"2026-03-04T16:31:26.081847Z","steps":["trace[229127795] 'process raft request' (duration: 117.328655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:31:26.346312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.940488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T16:31:26.346445Z","caller":"traceutil/trace.go:171","msg":"trace[1667785721] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"211.638137ms","start":"2026-03-04T16:31:26.134779Z","end":"2026-03-04T16:31:26.346417Z","steps":["trace[1667785721] 'process raft request' (duration: 53.511764ms)","trace[1667785721] 'compare' (duration: 157.785924ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:32:22.355814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.148076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fb698174-1ed8-4029-bee1-e440f9c495bd\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-04T16:32:22.355941Z","caller":"traceutil/trace.go:171","msg":"trace[2018296354] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fb698174-1ed8-4029-bee1-e440f9c495bd; range_end:; response_count:1; response_revision:1632; }","duration":"169.353983ms","start":"2026-03-04T16:32:22.186564Z","end":"2026-03-04T16:32:22.355918Z","steps":["trace[2018296354] 'range keys from in-memory index tree' (duration: 168.798767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:32:22.355968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.492011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-04T16:32:22.35605Z","caller":"traceutil/trace.go:171","msg":"trace[1853246950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1632; }","duration":"210.634796ms","start":"2026-03-04T16:32:22.145399Z","end":"2026-03-04T16:32:22.356034Z","steps":["trace[1853246950] 'range keys from in-memory index tree' (duration: 210.114432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:32:22.355933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.303819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-04T16:32:22.356137Z","caller":"traceutil/trace.go:171","msg":"trace[1831761295] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1632; }","duration":"164.599827ms","start":"2026-03-04T16:32:22.191527Z","end":"2026-03-04T16:32:22.356127Z","steps":["trace[1831761295] 'range keys from in-memory index tree' (duration: 163.856286ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:32:36.182529Z","caller":"traceutil/trace.go:171","msg":"trace[498255413] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"112.215749ms","start":"2026-03-04T16:32:36.07029Z","end":"2026-03-04T16:32:36.182505Z","steps":["trace[498255413] 'process raft request' (duration: 111.89514ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:33:06.877397Z","caller":"traceutil/trace.go:171","msg":"trace[248587720] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"138.881893ms","start":"2026-03-04T16:33:06.738474Z","end":"2026-03-04T16:33:06.877355Z","steps":["trace[248587720] 'process raft request' (duration: 129.316049ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:33:12.9956Z","caller":"traceutil/trace.go:171","msg":"trace[1984909081] linearizableReadLoop","detail":"{readStateIndex:2010; appliedIndex:2009; }","duration":"172.012639ms","start":"2026-03-04T16:33:12.823559Z","end":"2026-03-04T16:33:12.995571Z","steps":["trace[1984909081] 'read index received' (duration: 171.918687ms)","trace[1984909081] 'applied index is now lower than readState.Index' (duration: 92.582µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:33:12.995763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.171193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T16:33:12.995857Z","caller":"traceutil/trace.go:171","msg":"trace[532386926] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:1933; }","duration":"172.337518ms","start":"2026-03-04T16:33:12.8235Z","end":"2026-03-04T16:33:12.995838Z","steps":["trace[532386926] 'agreement among raft nodes before linearized reading' (duration: 172.149372ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:33:12.996206Z","caller":"traceutil/trace.go:171","msg":"trace[53350527] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"193.224594ms","start":"2026-03-04T16:33:12.802396Z","end":"2026-03-04T16:33:12.995621Z","steps":["trace[53350527] 'process raft request' (duration: 192.993057ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:33:18.633356Z","caller":"traceutil/trace.go:171","msg":"trace[1172440198] linearizableReadLoop","detail":"{readStateIndex:2037; appliedIndex:2036; }","duration":"210.640819ms","start":"2026-03-04T16:33:18.422689Z","end":"2026-03-04T16:33:18.63333Z","steps":["trace[1172440198] 'read index received' (duration: 210.505075ms)","trace[1172440198] 'applied index is now lower than readState.Index' (duration: 134.344µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:33:18.633424Z","caller":"traceutil/trace.go:171","msg":"trace[1856117100] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"230.678251ms","start":"2026-03-04T16:33:18.402714Z","end":"2026-03-04T16:33:18.633392Z","steps":["trace[1856117100] 'process raft request' (duration: 230.445274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:33:18.63361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.276735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T16:33:18.633658Z","caller":"traceutil/trace.go:171","msg":"trace[1442379386] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:1959; }","duration":"104.380248ms","start":"2026-03-04T16:33:18.529263Z","end":"2026-03-04T16:33:18.633643Z","steps":["trace[1442379386] 'agreement among raft nodes before linearized reading' (duration: 104.296056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:33:18.633642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.944368ms","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-04T16:33:18.633714Z","caller":"traceutil/trace.go:171","msg":"trace[357732221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1959; }","duration":"211.064333ms","start":"2026-03-04T16:33:18.42263Z","end":"2026-03-04T16:33:18.633695Z","steps":["trace[357732221] 'agreement among raft nodes before linearized reading' (duration: 210.871206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:33:18.989327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.576743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T16:33:18.989418Z","caller":"traceutil/trace.go:171","msg":"trace[889215197] linearizableReadLoop","detail":"{readStateIndex:2038; appliedIndex:2037; }","duration":"207.580723ms","start":"2026-03-04T16:33:18.781822Z","end":"2026-03-04T16:33:18.989403Z","steps":["trace[889215197] 'read index received' (duration: 6.773264ms)","trace[889215197] 'applied index is now lower than readState.Index' (duration: 200.805989ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:33:18.989559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.743768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-04T16:33:18.989535Z","caller":"traceutil/trace.go:171","msg":"trace[690358015] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"351.741806ms","start":"2026-03-04T16:33:18.637743Z","end":"2026-03-04T16:33:18.989484Z","steps":["trace[690358015] 'process raft request' (duration: 150.939427ms)","trace[690358015] 'compare' (duration: 200.48905ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:33:18.989599Z","caller":"traceutil/trace.go:171","msg":"trace[382898365] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1960; }","duration":"207.82276ms","start":"2026-03-04T16:33:18.781764Z","end":"2026-03-04T16:33:18.989587Z","steps":["trace[382898365] 'agreement among raft nodes before linearized reading' (duration: 207.676655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:33:18.989696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T16:33:18.637721Z","time spent":"351.890751ms","remote":"127.0.0.1:42932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T16:34:20.440688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.034784ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-04T16:38:08.14991Z","caller":"traceutil/trace.go:171","msg":"trace[216833877] transaction","detail":"{read_only:false; response_revision:3744; number_of_response:1; }","duration":"214.8119ms","start":"2026-03-04T16:38:07.935058Z","end":"2026-03-04T16:38:08.14987Z","steps":["trace[216833877] 'process raft request' (duration: 153.261119ms)","trace[216833877] 'compare' (duration: 61.261542ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:39:53.965281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.780329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-04T16:39:53.965423Z","caller":"traceutil/trace.go:171","msg":"trace[911384420] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4980; }","duration":"101.005025ms","start":"2026-03-04T16:39:53.864395Z","end":"2026-03-04T16:39:53.9654Z","steps":["trace[911384420] 'range keys from in-memory index tree' (duration: 100.485671ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:39:55.699207Z","caller":"traceutil/trace.go:171","msg":"trace[1974708849] linearizableReadLoop","detail":"{readStateIndex:5225; appliedIndex:5224; }","duration":"136.432923ms","start":"2026-03-04T16:39:55.562751Z","end":"2026-03-04T16:39:55.699184Z","steps":["trace[1974708849] 'read index received' (duration: 128.185868ms)","trace[1974708849] 'applied index is now lower than readState.Index' (duration: 8.245885ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:39:55.699371Z","caller":"traceutil/trace.go:171","msg":"trace[1402024296] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"145.672607ms","start":"2026-03-04T16:39:55.553633Z","end":"2026-03-04T16:39:55.699306Z","steps":["trace[1402024296] 'process raft request' (duration: 137.390551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:39:55.699474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.714101ms","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-04T16:39:55.699617Z","caller":"traceutil/trace.go:171","msg":"trace[963725127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5017; }","duration":"136.824294ms","start":"2026-03-04T16:39:55.562677Z","end":"2026-03-04T16:39:55.699501Z","steps":["trace[963725127] 'agreement among raft nodes before linearized reading' (duration: 136.636089ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:40:22.064033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2757} {"level":"info","ts":"2026-03-04T16:40:22.123326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2757,"took":"57.884114ms","hash":3647466796,"current-db-size-bytes":38436864,"current-db-size":"38 MB","current-db-size-in-use-bytes":28266496,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-04T16:40:22.123448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3647466796,"revision":2757,"compact-revision":-1} {"level":"info","ts":"2026-03-04T16:40:42.249959Z","caller":"traceutil/trace.go:171","msg":"trace[1512839525] linearizableReadLoop","detail":"{readStateIndex:5739; appliedIndex:5738; }","duration":"103.893638ms","start":"2026-03-04T16:40:42.146036Z","end":"2026-03-04T16:40:42.24993Z","steps":["trace[1512839525] 'read index received' (duration: 73.844011ms)","trace[1512839525] 'applied index is now lower than readState.Index' (duration: 30.048227ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:40:42.250194Z","caller":"traceutil/trace.go:171","msg":"trace[939925190] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"129.735175ms","start":"2026-03-04T16:40:42.120413Z","end":"2026-03-04T16:40:42.250148Z","steps":["trace[939925190] 'process raft request' (duration: 99.567054ms)","trace[939925190] 'compare' (duration: 29.389919ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T16:40:42.25039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.34289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/keycloak\" ","response":"range_response_count:1 size:963"} {"level":"info","ts":"2026-03-04T16:40:42.251272Z","caller":"traceutil/trace.go:171","msg":"trace[291909862] range","detail":"{range_begin:/registry/secrets/auth-system/keycloak; range_end:; response_count:1; response_revision:5516; }","duration":"105.257698ms","start":"2026-03-04T16:40:42.14597Z","end":"2026-03-04T16:40:42.251228Z","steps":["trace[291909862] 'agreement among raft nodes before linearized reading' (duration: 104.254368ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T16:40:47.940019Z","caller":"traceutil/trace.go:171","msg":"trace[575397839] linearizableReadLoop","detail":"{readStateIndex:5764; appliedIndex:5763; }","duration":"197.207494ms","start":"2026-03-04T16:40:47.742777Z","end":"2026-03-04T16:40:47.939984Z","steps":["trace[575397839] 'read index received' (duration: 196.989097ms)","trace[575397839] 'applied index is now lower than readState.Index' (duration: 217.257µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T16:40:47.940116Z","caller":"traceutil/trace.go:171","msg":"trace[253260784] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"246.309726ms","start":"2026-03-04T16:40:47.69377Z","end":"2026-03-04T16:40:47.940079Z","steps":["trace[253260784] 'process raft request' (duration: 245.976856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:40:47.940361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.507968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-04T16:40:47.940412Z","caller":"traceutil/trace.go:171","msg":"trace[386382268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5540; }","duration":"179.641781ms","start":"2026-03-04T16:40:47.760759Z","end":"2026-03-04T16:40:47.9404Z","steps":["trace[386382268] 'agreement among raft nodes before linearized reading' (duration: 179.436616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:40:47.940407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.596905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-04T16:40:47.94053Z","caller":"traceutil/trace.go:171","msg":"trace[253814168] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:5540; }","duration":"197.749429ms","start":"2026-03-04T16:40:47.742755Z","end":"2026-03-04T16:40:47.940505Z","steps":["trace[253814168] 'agreement among raft nodes before linearized reading' (duration: 197.375539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:40:47.940453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.18843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:1 size:612"} {"level":"info","ts":"2026-03-04T16:40:47.940689Z","caller":"traceutil/trace.go:171","msg":"trace[992726550] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:1; response_revision:5540; }","duration":"158.443757ms","start":"2026-03-04T16:40:47.782226Z","end":"2026-03-04T16:40:47.940669Z","steps":["trace[992726550] 'agreement among raft nodes before linearized reading' (duration: 158.083227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:40:47.94059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.572625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/\" range_end:\"/registry/serviceaccounts/monitoring0\" limit:500 ","response":"range_response_count:14 size:12978"} {"level":"info","ts":"2026-03-04T16:40:47.940819Z","caller":"traceutil/trace.go:171","msg":"trace[981716072] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/; range_end:/registry/serviceaccounts/monitoring0; response_count:14; response_revision:5540; }","duration":"155.833463ms","start":"2026-03-04T16:40:47.784962Z","end":"2026-03-04T16:40:47.940795Z","steps":["trace[981716072] 'agreement among raft nodes before linearized reading' (duration: 155.293187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T16:40:47.940589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.544784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-d5qvf\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-04T16:40:47.940947Z","caller":"traceutil/trace.go:171","msg":"trace[655633818] range","detail":"{range_begin:/registry/pods/openstack/keepalived-d5qvf; range_end:; response_count:1; response_revision:5540; }","duration":"162.936295ms","start":"2026-03-04T16:40:47.777981Z","end":"2026-03-04T16:40:47.940918Z","steps":["trace[655633818] 'agreement among raft nodes before linearized reading' (duration: 162.353119ms)"],"step_count":1}