{"level":"warn","ts":"2026-05-13T08:40:12.271792Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-13T08:40:12.27188Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.111:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.111:2380","--initial-cluster=instance=https://199.19.213.111:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.111:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.111: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-05-13T08:40:12.271947Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-13T08:40:12.271975Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.111:2380"]} {"level":"info","ts":"2026-05-13T08:40:12.272011Z","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-05-13T08:40:12.272837Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"]} {"level":"info","ts":"2026-05-13T08:40:12.272924Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.111:2380"],"listen-peer-urls":["https://199.19.213.111:2380"],"advertise-client-urls":["https://199.19.213.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.111: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-05-13T08:40:12.276912Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.474955ms"} {"level":"info","ts":"2026-05-13T08:40:12.282674Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4edbe0dc99dceaa8","cluster-id":"9f725a00b80fcc82"} {"level":"info","ts":"2026-05-13T08:40:12.282742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=()"} {"level":"info","ts":"2026-05-13T08:40:12.282765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became follower at term 0"} {"level":"info","ts":"2026-05-13T08:40:12.282775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4edbe0dc99dceaa8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-13T08:40:12.282795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became follower at term 1"} {"level":"info","ts":"2026-05-13T08:40:12.282826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=(5682382592936831656)"} {"level":"warn","ts":"2026-05-13T08:40:12.28969Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-13T08:40:12.290956Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-13T08:40:12.291966Z","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-05-13T08:40:12.294103Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4edbe0dc99dceaa8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-13T08:40:12.295302Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4edbe0dc99dceaa8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-13T08:40:12.295979Z","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-05-13T08:40:12.29609Z","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-05-13T08:40:12.296106Z","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-05-13T08:40:12.297181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 switched to configuration voters=(5682382592936831656)"} {"level":"info","ts":"2026-05-13T08:40:12.297275Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9f725a00b80fcc82","local-member-id":"4edbe0dc99dceaa8","added-peer-id":"4edbe0dc99dceaa8","added-peer-peer-urls":["https://199.19.213.111:2380"]} {"level":"info","ts":"2026-05-13T08:40:12.298086Z","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-05-13T08:40:12.298378Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4edbe0dc99dceaa8","initial-advertise-peer-urls":["https://199.19.213.111:2380"],"listen-peer-urls":["https://199.19.213.111:2380"],"advertise-client-urls":["https://199.19.213.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-13T08:40:12.298403Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-13T08:40:12.298164Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.111:2380"} {"level":"info","ts":"2026-05-13T08:40:12.298423Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.111:2380"} {"level":"info","ts":"2026-05-13T08:40:12.48316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 is starting a new election at term 1"} {"level":"info","ts":"2026-05-13T08:40:12.483599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-13T08:40:12.483747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 received MsgPreVoteResp from 4edbe0dc99dceaa8 at term 1"} {"level":"info","ts":"2026-05-13T08:40:12.483867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became candidate at term 2"} {"level":"info","ts":"2026-05-13T08:40:12.483893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 received MsgVoteResp from 4edbe0dc99dceaa8 at term 2"} {"level":"info","ts":"2026-05-13T08:40:12.484029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4edbe0dc99dceaa8 became leader at term 2"} {"level":"info","ts":"2026-05-13T08:40:12.484059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4edbe0dc99dceaa8 elected leader 4edbe0dc99dceaa8 at term 2"} {"level":"info","ts":"2026-05-13T08:40:12.48543Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T08:40:12.4858Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4edbe0dc99dceaa8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.111:2379]}","request-path":"/0/members/4edbe0dc99dceaa8/attributes","cluster-id":"9f725a00b80fcc82","publish-timeout":"7s"} {"level":"info","ts":"2026-05-13T08:40:12.48583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-13T08:40:12.485801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-13T08:40:12.486286Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-13T08:40:12.486327Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-13T08:40:12.488932Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-13T08:40:12.490122Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.111:2379"} {"level":"info","ts":"2026-05-13T08:40:12.49043Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9f725a00b80fcc82","local-member-id":"4edbe0dc99dceaa8","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T08:40:12.490645Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T08:40:12.490773Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-13T08:40:22.518411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.229728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-13T08:40:22.518498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.256659ms","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-05-13T08:40:22.518547Z","caller":"traceutil/trace.go:171","msg":"trace[438984016] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:328; }","duration":"377.438649ms","start":"2026-05-13T08:40:22.141085Z","end":"2026-05-13T08:40:22.518524Z","steps":["trace[438984016] 'range keys from in-memory index tree' (duration: 377.134403ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:40:22.518599Z","caller":"traceutil/trace.go:171","msg":"trace[783580317] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:328; }","duration":"195.410386ms","start":"2026-05-13T08:40:22.32317Z","end":"2026-05-13T08:40:22.51858Z","steps":["trace[783580317] 'range keys from in-memory index tree' (duration: 195.149314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:40:22.518608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:40:22.141072Z","time spent":"377.524822ms","remote":"127.0.0.1:36110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-05-13T08:40:22.807803Z","caller":"traceutil/trace.go:171","msg":"trace[1405144100] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"281.805749ms","start":"2026-05-13T08:40:22.525945Z","end":"2026-05-13T08:40:22.807751Z","steps":["trace[1405144100] 'process raft request' (duration: 281.607579ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:40:22.913322Z","caller":"traceutil/trace.go:171","msg":"trace[290426579] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"304.928674ms","start":"2026-05-13T08:40:22.608369Z","end":"2026-05-13T08:40:22.913298Z","steps":["trace[290426579] 'read index received' (duration: 199.419706ms)","trace[290426579] 'applied index is now lower than readState.Index' (duration: 105.507938ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T08:40:22.913366Z","caller":"traceutil/trace.go:171","msg":"trace[2017745073] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"386.651025ms","start":"2026-05-13T08:40:22.526691Z","end":"2026-05-13T08:40:22.913342Z","steps":["trace[2017745073] 'process raft request' (duration: 385.843947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:40:22.913539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.150785ms","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-05-13T08:40:22.913589Z","caller":"traceutil/trace.go:171","msg":"trace[1116687509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:330; }","duration":"305.254419ms","start":"2026-05-13T08:40:22.608319Z","end":"2026-05-13T08:40:22.913573Z","steps":["trace[1116687509] 'agreement among raft nodes before linearized reading' (duration: 305.122283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:40:22.913652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:40:22.608293Z","time spent":"305.348104ms","remote":"127.0.0.1:36162","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-05-13T08:40:22.913849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:40:22.526686Z","time spent":"386.75374ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T08:40:46.476209Z","caller":"traceutil/trace.go:171","msg":"trace[1245479868] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"280.541266ms","start":"2026-05-13T08:40:46.195637Z","end":"2026-05-13T08:40:46.476178Z","steps":["trace[1245479868] 'process raft request' (duration: 279.712416ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:40:46.684753Z","caller":"traceutil/trace.go:171","msg":"trace[73055064] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"174.91901ms","start":"2026-05-13T08:40:46.509787Z","end":"2026-05-13T08:40:46.684706Z","steps":["trace[73055064] 'process raft request' (duration: 174.767584ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:40:46.688613Z","caller":"traceutil/trace.go:171","msg":"trace[1697838993] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"160.539386ms","start":"2026-05-13T08:40:46.528048Z","end":"2026-05-13T08:40:46.688588Z","steps":["trace[1697838993] 'process raft request' (duration: 160.09036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:40:47.722314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.654463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T08:40:47.722544Z","caller":"traceutil/trace.go:171","msg":"trace[1736046749] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"644.623153ms","start":"2026-05-13T08:40:47.077888Z","end":"2026-05-13T08:40:47.722511Z","steps":["trace[1736046749] 'process raft request' (duration: 375.417711ms)","trace[1736046749] 'compare' (duration: 268.505557ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T08:40:47.722799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:40:47.077874Z","time spent":"644.846572ms","remote":"127.0.0.1:36162","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-05-13T08:40:47.722964Z","caller":"traceutil/trace.go:171","msg":"trace[2092127296] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"643.040627ms","start":"2026-05-13T08:40:47.079895Z","end":"2026-05-13T08:40:47.722935Z","steps":["trace[2092127296] 'process raft request' (duration: 642.540759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:40:47.723031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:40:47.079876Z","time spent":"643.123229ms","remote":"127.0.0.1:36162","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-05-13T08:40:47.72346Z","caller":"traceutil/trace.go:171","msg":"trace[751117859] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:587; }","duration":"278.364033ms","start":"2026-05-13T08:40:47.445076Z","end":"2026-05-13T08:40:47.72344Z","steps":["trace[751117859] 'read index received' (duration: 8.161864ms)","trace[751117859] 'applied index is now lower than readState.Index' (duration: 270.200909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T08:40:47.724131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.054417ms","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-05-13T08:40:47.724182Z","caller":"traceutil/trace.go:171","msg":"trace[1290812962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"279.151151ms","start":"2026-05-13T08:40:47.445017Z","end":"2026-05-13T08:40:47.724169Z","steps":["trace[1290812962] 'agreement among raft nodes before linearized reading' (duration: 278.991056ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:40:47.724323Z","caller":"traceutil/trace.go:171","msg":"trace[506478018] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"642.479757ms","start":"2026-05-13T08:40:47.081833Z","end":"2026-05-13T08:40:47.724313Z","steps":["trace[506478018] 'process raft request' (duration: 641.001443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:40:47.724384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:40:47.081805Z","time spent":"642.545679ms","remote":"127.0.0.1:35990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T08:41:03.196972Z","caller":"traceutil/trace.go:171","msg":"trace[328229807] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"131.697718ms","start":"2026-05-13T08:41:03.065249Z","end":"2026-05-13T08:41:03.196946Z","steps":["trace[328229807] 'read index received' (duration: 131.363478ms)","trace[328229807] 'applied index is now lower than readState.Index' (duration: 333.44µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T08:41:03.197201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.934205ms","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-05-13T08:41:03.197258Z","caller":"traceutil/trace.go:171","msg":"trace[1419042820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:732; }","duration":"132.038468ms","start":"2026-05-13T08:41:03.065208Z","end":"2026-05-13T08:41:03.197247Z","steps":["trace[1419042820] 'agreement among raft nodes before linearized reading' (duration: 131.843242ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:41:03.197242Z","caller":"traceutil/trace.go:171","msg":"trace[759770978] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"169.849504ms","start":"2026-05-13T08:41:03.027368Z","end":"2026-05-13T08:41:03.197218Z","steps":["trace[759770978] 'process raft request' (duration: 169.323787ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:41:03.315575Z","caller":"traceutil/trace.go:171","msg":"trace[2104582755] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"114.279782ms","start":"2026-05-13T08:41:03.201269Z","end":"2026-05-13T08:41:03.315549Z","steps":["trace[2104582755] 'process raft request' (duration: 114.124707ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:42:19.274317Z","caller":"traceutil/trace.go:171","msg":"trace[1355803777] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"314.47681ms","start":"2026-05-13T08:42:18.959809Z","end":"2026-05-13T08:42:19.274285Z","steps":["trace[1355803777] 'process raft request' (duration: 314.263446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:42:19.274504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:42:18.95978Z","time spent":"314.628152ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T08:42:19.684724Z","caller":"traceutil/trace.go:171","msg":"trace[543505408] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"124.361739ms","start":"2026-05-13T08:42:19.560338Z","end":"2026-05-13T08:42:19.6847Z","steps":["trace[543505408] 'process raft request' (duration: 124.176326ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:42:19.812797Z","caller":"traceutil/trace.go:171","msg":"trace[476816881] linearizableReadLoop","detail":"{readStateIndex:1876; appliedIndex:1874; }","duration":"140.53512ms","start":"2026-05-13T08:42:19.672237Z","end":"2026-05-13T08:42:19.812772Z","steps":["trace[476816881] 'read index received' (duration: 12.16588ms)","trace[476816881] 'applied index is now lower than readState.Index' (duration: 128.36795ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T08:42:19.812899Z","caller":"traceutil/trace.go:171","msg":"trace[1408341865] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"176.375449ms","start":"2026-05-13T08:42:19.636481Z","end":"2026-05-13T08:42:19.812856Z","steps":["trace[1408341865] 'process raft request' (duration: 107.117467ms)","trace[1408341865] 'compare' (duration: 69.062178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T08:42:19.81296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.712744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T08:42:19.813059Z","caller":"traceutil/trace.go:171","msg":"trace[679358479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1811; }","duration":"140.886777ms","start":"2026-05-13T08:42:19.672153Z","end":"2026-05-13T08:42:19.81304Z","steps":["trace[679358479] 'agreement among raft nodes before linearized reading' (duration: 140.717674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:42:19.813152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.215507ms","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-05-13T08:42:19.813231Z","caller":"traceutil/trace.go:171","msg":"trace[1823885215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1811; }","duration":"118.36945ms","start":"2026-05-13T08:42:19.694847Z","end":"2026-05-13T08:42:19.813216Z","steps":["trace[1823885215] 'agreement among raft nodes before linearized reading' (duration: 118.110786ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:42:34.43422Z","caller":"traceutil/trace.go:171","msg":"trace[1970177534] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2008; }","duration":"187.874469ms","start":"2026-05-13T08:42:34.246325Z","end":"2026-05-13T08:42:34.434199Z","steps":["trace[1970177534] 'read index received' (duration: 187.797288ms)","trace[1970177534] 'applied index is now lower than readState.Index' (duration: 76.231µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T08:42:34.434327Z","caller":"traceutil/trace.go:171","msg":"trace[177390968] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"196.916172ms","start":"2026-05-13T08:42:34.237382Z","end":"2026-05-13T08:42:34.434298Z","steps":["trace[177390968] 'process raft request' (duration: 196.673058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:42:34.43456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.212294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-13T08:42:34.43464Z","caller":"traceutil/trace.go:171","msg":"trace[769343786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1940; }","duration":"188.340827ms","start":"2026-05-13T08:42:34.246281Z","end":"2026-05-13T08:42:34.434622Z","steps":["trace[769343786] 'agreement among raft nodes before linearized reading' (duration: 188.059212ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:42:34.442676Z","caller":"traceutil/trace.go:171","msg":"trace[338227910] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"133.701952ms","start":"2026-05-13T08:42:34.308954Z","end":"2026-05-13T08:42:34.442656Z","steps":["trace[338227910] 'process raft request' (duration: 133.546889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:42:34.442702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.659696ms","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-05-13T08:42:34.442764Z","caller":"traceutil/trace.go:171","msg":"trace[92225239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1941; }","duration":"193.744458ms","start":"2026-05-13T08:42:34.249007Z","end":"2026-05-13T08:42:34.442751Z","steps":["trace[92225239] 'agreement among raft nodes before linearized reading' (duration: 193.576655ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:42:52.660334Z","caller":"traceutil/trace.go:171","msg":"trace[1653417246] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"125.944625ms","start":"2026-05-13T08:42:52.534363Z","end":"2026-05-13T08:42:52.660307Z","steps":["trace[1653417246] 'process raft request' (duration: 96.319456ms)","trace[1653417246] 'compare' (duration: 29.122481ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T08:42:53.973062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.243664ms","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-05-13T08:42:53.973155Z","caller":"traceutil/trace.go:171","msg":"trace[1498004300] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2052; }","duration":"183.406446ms","start":"2026-05-13T08:42:53.789731Z","end":"2026-05-13T08:42:53.973137Z","steps":["trace[1498004300] 'range keys from in-memory index tree' (duration: 183.03616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:42:53.973208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.164883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-13T08:42:53.973378Z","caller":"traceutil/trace.go:171","msg":"trace[1874097995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2052; }","duration":"145.359746ms","start":"2026-05-13T08:42:53.828002Z","end":"2026-05-13T08:42:53.973362Z","steps":["trace[1874097995] 'range keys from in-memory index tree' (duration: 145.008941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:42:53.973274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.67338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T08:42:53.973527Z","caller":"traceutil/trace.go:171","msg":"trace[644375755] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2052; }","duration":"390.974285ms","start":"2026-05-13T08:42:53.582521Z","end":"2026-05-13T08:42:53.973495Z","steps":["trace[644375755] 'count revisions from in-memory index tree' (duration: 390.523728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:42:53.973646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:42:53.582498Z","time spent":"391.096717ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":25,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-05-13T08:43:49.042583Z","caller":"traceutil/trace.go:171","msg":"trace[1075023733] linearizableReadLoop","detail":"{readStateIndex:2477; appliedIndex:2475; }","duration":"136.306927ms","start":"2026-05-13T08:43:48.906248Z","end":"2026-05-13T08:43:49.042555Z","steps":["trace[1075023733] 'read index received' (duration: 135.951473ms)","trace[1075023733] 'applied index is now lower than readState.Index' (duration: 354.344µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T08:43:49.042954Z","caller":"traceutil/trace.go:171","msg":"trace[321805586] transaction","detail":"{read_only:false; response_revision:2390; number_of_response:1; }","duration":"205.020314ms","start":"2026-05-13T08:43:48.837916Z","end":"2026-05-13T08:43:49.042937Z","steps":["trace[321805586] 'process raft request' (duration: 204.370665ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:43:49.043212Z","caller":"traceutil/trace.go:171","msg":"trace[1996173628] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"142.344481ms","start":"2026-05-13T08:43:48.900854Z","end":"2026-05-13T08:43:49.043199Z","steps":["trace[1996173628] 'process raft request' (duration: 141.606191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:43:49.043614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.342552ms","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-05-13T08:43:49.043658Z","caller":"traceutil/trace.go:171","msg":"trace[2141708482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2392; }","duration":"137.425033ms","start":"2026-05-13T08:43:48.90622Z","end":"2026-05-13T08:43:49.043645Z","steps":["trace[2141708482] 'agreement among raft nodes before linearized reading' (duration: 137.25664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:43:49.043911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.037798ms","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-05-13T08:43:49.043946Z","caller":"traceutil/trace.go:171","msg":"trace[1893593491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2392; }","duration":"132.098179ms","start":"2026-05-13T08:43:48.911837Z","end":"2026-05-13T08:43:49.043935Z","steps":["trace[1893593491] 'agreement among raft nodes before linearized reading' (duration: 131.986247ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:43:57.385997Z","caller":"traceutil/trace.go:171","msg":"trace[1104522620] linearizableReadLoop","detail":"{readStateIndex:2584; appliedIndex:2581; }","duration":"304.908671ms","start":"2026-05-13T08:43:57.081064Z","end":"2026-05-13T08:43:57.385973Z","steps":["trace[1104522620] 'read index received' (duration: 25.060205ms)","trace[1104522620] 'applied index is now lower than readState.Index' (duration: 279.847556ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T08:43:57.386298Z","caller":"traceutil/trace.go:171","msg":"trace[1492667300] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"311.251148ms","start":"2026-05-13T08:43:57.075024Z","end":"2026-05-13T08:43:57.386275Z","steps":["trace[1492667300] 'process raft request' (duration: 310.762632ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:43:57.386303Z","caller":"traceutil/trace.go:171","msg":"trace[1704554632] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"309.22643ms","start":"2026-05-13T08:43:57.077024Z","end":"2026-05-13T08:43:57.38625Z","steps":["trace[1704554632] 'process raft request' (duration: 308.902525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:43:57.38638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.301946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-13T08:43:57.386446Z","caller":"traceutil/trace.go:171","msg":"trace[1808015679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2496; }","duration":"305.407087ms","start":"2026-05-13T08:43:57.081025Z","end":"2026-05-13T08:43:57.386432Z","steps":["trace[1808015679] 'agreement among raft nodes before linearized reading' (duration: 305.227095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:43:57.386446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:43:57.074995Z","time spent":"311.358959ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T08:43:57.386482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:43:57.081012Z","time spent":"305.455669ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-13T08:43:57.386652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:43:57.077012Z","time spent":"309.409292ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T08:43:57.388303Z","caller":"traceutil/trace.go:171","msg":"trace[254121096] transaction","detail":"{read_only:false; response_revision:2497; number_of_response:1; }","duration":"237.946421ms","start":"2026-05-13T08:43:57.150344Z","end":"2026-05-13T08:43:57.38829Z","steps":["trace[254121096] 'process raft request' (duration: 237.736768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:43:57.388389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.838082ms","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-05-13T08:43:57.388465Z","caller":"traceutil/trace.go:171","msg":"trace[1024866549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2497; }","duration":"302.955144ms","start":"2026-05-13T08:43:57.085492Z","end":"2026-05-13T08:43:57.388447Z","steps":["trace[1024866549] 'agreement among raft nodes before linearized reading' (duration: 302.741401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:43:57.388513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:43:57.085476Z","time spent":"303.028145ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-13T08:43:57.388613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.28282ms","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-05-13T08:43:57.388702Z","caller":"traceutil/trace.go:171","msg":"trace[1014446337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2497; }","duration":"301.427643ms","start":"2026-05-13T08:43:57.087251Z","end":"2026-05-13T08:43:57.388679Z","steps":["trace[1014446337] 'agreement among raft nodes before linearized reading' (duration: 301.16227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:43:57.388748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T08:43:57.087244Z","time spent":"301.494794ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-13T08:44:33.936478Z","caller":"traceutil/trace.go:171","msg":"trace[921387671] linearizableReadLoop","detail":"{readStateIndex:2794; appliedIndex:2793; }","duration":"167.427998ms","start":"2026-05-13T08:44:33.769023Z","end":"2026-05-13T08:44:33.936451Z","steps":["trace[921387671] 'read index received' (duration: 165.77807ms)","trace[921387671] 'applied index is now lower than readState.Index' (duration: 1.648778ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T08:44:33.936558Z","caller":"traceutil/trace.go:171","msg":"trace[1065696092] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"169.300655ms","start":"2026-05-13T08:44:33.767209Z","end":"2026-05-13T08:44:33.93651Z","steps":["trace[1065696092] 'process raft request' (duration: 168.082282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:44:33.93674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.705098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T08:44:33.936833Z","caller":"traceutil/trace.go:171","msg":"trace[915122098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2698; }","duration":"167.806251ms","start":"2026-05-13T08:44:33.769003Z","end":"2026-05-13T08:44:33.936809Z","steps":["trace[915122098] 'agreement among raft nodes before linearized reading' (duration: 167.608544ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:48:29.964686Z","caller":"traceutil/trace.go:171","msg":"trace[1238575822] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"114.669545ms","start":"2026-05-13T08:48:29.849988Z","end":"2026-05-13T08:48:29.964658Z","steps":["trace[1238575822] 'process raft request' (duration: 89.092895ms)","trace[1238575822] 'compare' (duration: 25.449337ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T08:48:35.779079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.869073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T08:48:35.779164Z","caller":"traceutil/trace.go:171","msg":"trace[512132287] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:4761; }","duration":"120.999506ms","start":"2026-05-13T08:48:35.658149Z","end":"2026-05-13T08:48:35.779148Z","steps":["trace[512132287] 'count revisions from in-memory index tree' (duration: 120.750111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:48:35.779289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.045238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T08:48:35.779358Z","caller":"traceutil/trace.go:171","msg":"trace[473977167] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4761; }","duration":"233.123939ms","start":"2026-05-13T08:48:35.546214Z","end":"2026-05-13T08:48:35.779338Z","steps":["trace[473977167] 'count revisions from in-memory index tree' (duration: 232.977106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:48:35.77941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.07193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T08:48:35.779503Z","caller":"traceutil/trace.go:171","msg":"trace[1770542024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4761; }","duration":"108.202352ms","start":"2026-05-13T08:48:35.671274Z","end":"2026-05-13T08:48:35.779476Z","steps":["trace[1770542024] 'range keys from in-memory index tree' (duration: 107.988778ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:48:35.921307Z","caller":"traceutil/trace.go:171","msg":"trace[1678199640] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"226.140032ms","start":"2026-05-13T08:48:35.695132Z","end":"2026-05-13T08:48:35.921272Z","steps":["trace[1678199640] 'process raft request' (duration: 225.805016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:48:35.921593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.514807ms","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-05-13T08:48:35.921676Z","caller":"traceutil/trace.go:171","msg":"trace[1873896394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4762; }","duration":"165.635719ms","start":"2026-05-13T08:48:35.756028Z","end":"2026-05-13T08:48:35.921663Z","steps":["trace[1873896394] 'agreement among raft nodes before linearized reading' (duration: 165.337433ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:48:35.921277Z","caller":"traceutil/trace.go:171","msg":"trace[627498877] linearizableReadLoop","detail":"{readStateIndex:4935; appliedIndex:4934; }","duration":"165.206081ms","start":"2026-05-13T08:48:35.756042Z","end":"2026-05-13T08:48:35.921248Z","steps":["trace[627498877] 'read index received' (duration: 164.932356ms)","trace[627498877] 'applied index is now lower than readState.Index' (duration: 271.625µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T08:48:35.951585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.721916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-13T08:48:35.951602Z","caller":"traceutil/trace.go:171","msg":"trace[1247553270] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"160.825171ms","start":"2026-05-13T08:48:35.790752Z","end":"2026-05-13T08:48:35.951577Z","steps":["trace[1247553270] 'process raft request' (duration: 160.557976ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:48:35.951651Z","caller":"traceutil/trace.go:171","msg":"trace[512002003] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4763; }","duration":"190.817598ms","start":"2026-05-13T08:48:35.760816Z","end":"2026-05-13T08:48:35.951634Z","steps":["trace[512002003] 'agreement among raft nodes before linearized reading' (duration: 190.630124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:48:35.951839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.658815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9da73705-50e1-4c30-8717-342125685834\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-13T08:48:35.95188Z","caller":"traceutil/trace.go:171","msg":"trace[1839514234] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9da73705-50e1-4c30-8717-342125685834; range_end:; response_count:1; response_revision:4763; }","duration":"167.731007ms","start":"2026-05-13T08:48:35.784138Z","end":"2026-05-13T08:48:35.951869Z","steps":["trace[1839514234] 'agreement among raft nodes before linearized reading' (duration: 167.617005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:48:35.951883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.630717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-13T08:48:35.951986Z","caller":"traceutil/trace.go:171","msg":"trace[1005053800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4763; }","duration":"188.7734ms","start":"2026-05-13T08:48:35.763203Z","end":"2026-05-13T08:48:35.951977Z","steps":["trace[1005053800] 'agreement among raft nodes before linearized reading' (duration: 188.615857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:48:42.141353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.282518ms","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-05-13T08:48:42.14144Z","caller":"traceutil/trace.go:171","msg":"trace[974161478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4822; }","duration":"159.419251ms","start":"2026-05-13T08:48:41.982002Z","end":"2026-05-13T08:48:42.141421Z","steps":["trace[974161478] 'range keys from in-memory index tree' (duration: 159.101325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:48:42.141687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.450506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-13T08:48:42.14174Z","caller":"traceutil/trace.go:171","msg":"trace[341502175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4822; }","duration":"146.550647ms","start":"2026-05-13T08:48:41.995176Z","end":"2026-05-13T08:48:42.141727Z","steps":["trace[341502175] 'range keys from in-memory index tree' (duration: 146.326044ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:48:42.323718Z","caller":"traceutil/trace.go:171","msg":"trace[1397194834] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"178.341371ms","start":"2026-05-13T08:48:42.145348Z","end":"2026-05-13T08:48:42.32369Z","steps":["trace[1397194834] 'process raft request' (duration: 164.655964ms)","trace[1397194834] 'compare' (duration: 13.583405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T08:49:17.811548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.721786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T08:49:17.811703Z","caller":"traceutil/trace.go:171","msg":"trace[1816028049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5196; }","duration":"140.93449ms","start":"2026-05-13T08:49:17.67074Z","end":"2026-05-13T08:49:17.811674Z","steps":["trace[1816028049] 'range keys from in-memory index tree' (duration: 140.604793ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:49:20.66468Z","caller":"traceutil/trace.go:171","msg":"trace[707799506] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"127.404748ms","start":"2026-05-13T08:49:20.53724Z","end":"2026-05-13T08:49:20.664645Z","steps":["trace[707799506] 'process raft request' (duration: 47.532613ms)","trace[707799506] 'compare' (duration: 79.03657ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T08:50:12.924701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2904} {"level":"info","ts":"2026-05-13T08:50:13.012515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2904,"took":"86.341575ms","hash":369224383,"current-db-size-bytes":53108736,"current-db-size":"53 MB","current-db-size-in-use-bytes":28925952,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-13T08:50:13.01259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":369224383,"revision":2904,"compact-revision":-1} {"level":"info","ts":"2026-05-13T08:52:15.477374Z","caller":"traceutil/trace.go:171","msg":"trace[1182528111] transaction","detail":"{read_only:false; response_revision:6412; number_of_response:1; }","duration":"178.864377ms","start":"2026-05-13T08:52:15.298478Z","end":"2026-05-13T08:52:15.477342Z","steps":["trace[1182528111] 'process raft request' (duration: 178.653793ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:52:15.544947Z","caller":"traceutil/trace.go:171","msg":"trace[1499918822] transaction","detail":"{read_only:false; response_revision:6413; number_of_response:1; }","duration":"235.947065ms","start":"2026-05-13T08:52:15.308946Z","end":"2026-05-13T08:52:15.544893Z","steps":["trace[1499918822] 'process raft request' (duration: 235.668601ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:52:15.545002Z","caller":"traceutil/trace.go:171","msg":"trace[1536300125] transaction","detail":"{read_only:false; response_revision:6414; number_of_response:1; }","duration":"203.825519ms","start":"2026-05-13T08:52:15.341126Z","end":"2026-05-13T08:52:15.544952Z","steps":["trace[1536300125] 'process raft request' (duration: 203.615466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T08:52:15.545358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.575116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-13T08:52:15.545414Z","caller":"traceutil/trace.go:171","msg":"trace[1406244259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6415; }","duration":"163.709538ms","start":"2026-05-13T08:52:15.381695Z","end":"2026-05-13T08:52:15.545405Z","steps":["trace[1406244259] 'agreement among raft nodes before linearized reading' (duration: 163.600756ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:52:15.545259Z","caller":"traceutil/trace.go:171","msg":"trace[1330429594] transaction","detail":"{read_only:false; response_revision:6415; number_of_response:1; }","duration":"203.1853ms","start":"2026-05-13T08:52:15.342056Z","end":"2026-05-13T08:52:15.545241Z","steps":["trace[1330429594] 'process raft request' (duration: 203.006437ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:52:15.545942Z","caller":"traceutil/trace.go:171","msg":"trace[2087164497] linearizableReadLoop","detail":"{readStateIndex:6644; appliedIndex:6640; }","duration":"163.419083ms","start":"2026-05-13T08:52:15.38177Z","end":"2026-05-13T08:52:15.54519Z","steps":["trace[2087164497] 'read index received' (duration: 95.29392ms)","trace[2087164497] 'applied index is now lower than readState.Index' (duration: 68.123683ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T08:52:41.299737Z","caller":"traceutil/trace.go:171","msg":"trace[1184421757] transaction","detail":"{read_only:false; response_revision:6577; number_of_response:1; }","duration":"125.401053ms","start":"2026-05-13T08:52:41.174306Z","end":"2026-05-13T08:52:41.299708Z","steps":["trace[1184421757] 'process raft request' (duration: 125.17964ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T08:55:12.931137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5575} {"level":"info","ts":"2026-05-13T08:55:13.037255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5575,"took":"104.492609ms","hash":1440155917,"current-db-size-bytes":53108736,"current-db-size":"53 MB","current-db-size-in-use-bytes":18141184,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-13T08:55:13.037315Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1440155917,"revision":5575,"compact-revision":2904}