{"level":"warn","ts":"2026-10-16T16:28:33.924698Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T16:28:33.924791Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.159: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.159:2380","--initial-cluster=instance=https://162.253.55.159:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.159:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.159: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-10-16T16:28:33.924866Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-16T16:28:33.924879Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-10-16T16:28:33.924898Z","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-10-16T16:28:33.925591Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"]} {"level":"info","ts":"2026-10-16T16:28:33.925677Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.159: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-10-16T16:28:33.933623Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.758207ms"} {"level":"info","ts":"2026-10-16T16:28:33.964238Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"94bfbb0e034a3ac2","cluster-id":"9676abcf1742949"} {"level":"info","ts":"2026-10-16T16:28:33.964296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T16:28:33.964318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 0"} {"level":"info","ts":"2026-10-16T16:28:33.964346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 94bfbb0e034a3ac2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T16:28:33.964353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 1"} {"level":"info","ts":"2026-10-16T16:28:33.964391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"warn","ts":"2026-10-16T16:28:33.974428Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T16:28:33.978994Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T16:28:33.983072Z","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-10-16T16:28:33.987029Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"94bfbb0e034a3ac2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T16:28:33.987285Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"94bfbb0e034a3ac2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T16:28:33.987391Z","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-10-16T16:28:33.987461Z","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-10-16T16:28:33.987472Z","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-10-16T16:28:33.988243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"info","ts":"2026-10-16T16:28:33.988406Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","added-peer-id":"94bfbb0e034a3ac2","added-peer-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-10-16T16:28:33.992418Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-10-16T16:28:33.992449Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-10-16T16:28:33.992287Z","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-10-16T16:28:33.992948Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"94bfbb0e034a3ac2","initial-advertise-peer-urls":["https://162.253.55.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T16:28:33.993015Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T16:28:34.065375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T16:28:34.065431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T16:28:34.065459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgPreVoteResp from 94bfbb0e034a3ac2 at term 1"} {"level":"info","ts":"2026-10-16T16:28:34.065482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became candidate at term 2"} {"level":"info","ts":"2026-10-16T16:28:34.065498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgVoteResp from 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-10-16T16:28:34.065512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became leader at term 2"} {"level":"info","ts":"2026-10-16T16:28:34.065529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 94bfbb0e034a3ac2 elected leader 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-10-16T16:28:34.066839Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"94bfbb0e034a3ac2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.159:2379]}","request-path":"/0/members/94bfbb0e034a3ac2/attributes","cluster-id":"9676abcf1742949","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T16:28:34.066912Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T16:28:34.066967Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T16:28:34.067114Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T16:28:34.067298Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T16:28:34.067338Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T16:28:34.068356Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T16:28:34.068483Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T16:28:34.068517Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T16:28:34.069307Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T16:28:34.069417Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.159:2379"} {"level":"info","ts":"2026-10-16T16:28:58.816548Z","caller":"traceutil/trace.go:171","msg":"trace[964572136] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"173.382524ms","start":"2026-10-16T16:28:58.643142Z","end":"2026-10-16T16:28:58.816524Z","steps":["trace[964572136] 'read index received' (duration: 173.200979ms)","trace[964572136] 'applied index is now lower than readState.Index' (duration: 176.985µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:28:58.816662Z","caller":"traceutil/trace.go:171","msg":"trace[900418463] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"240.063592ms","start":"2026-10-16T16:28:58.576589Z","end":"2026-10-16T16:28:58.816653Z","steps":["trace[900418463] 'process raft request' (duration: 239.794504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:28:58.816796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.636181ms","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-10-16T16:28:58.816842Z","caller":"traceutil/trace.go:171","msg":"trace[1930450918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"173.723473ms","start":"2026-10-16T16:28:58.643109Z","end":"2026-10-16T16:28:58.816832Z","steps":["trace[1930450918] 'agreement among raft nodes before linearized reading' (duration: 173.643141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:28:58.816949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.641763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T16:28:58.816978Z","caller":"traceutil/trace.go:171","msg":"trace[1931923097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:391; }","duration":"169.688304ms","start":"2026-10-16T16:28:58.647283Z","end":"2026-10-16T16:28:58.816971Z","steps":["trace[1931923097] 'agreement among raft nodes before linearized reading' (duration: 169.645323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:29:22.647081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.739333ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T16:29:22.64736Z","caller":"traceutil/trace.go:171","msg":"trace[613541441] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:602; }","duration":"223.231597ms","start":"2026-10-16T16:29:22.424116Z","end":"2026-10-16T16:29:22.647348Z","steps":["trace[613541441] 'range keys from in-memory index tree' (duration: 222.722442ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:29:22.687134Z","caller":"traceutil/trace.go:171","msg":"trace[588960513] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"183.397397ms","start":"2026-10-16T16:29:22.503715Z","end":"2026-10-16T16:29:22.687112Z","steps":["trace[588960513] 'read index received' (duration: 182.666497ms)","trace[588960513] 'applied index is now lower than readState.Index' (duration: 729.96µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:29:22.687186Z","caller":"traceutil/trace.go:171","msg":"trace[1726138640] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"365.57571ms","start":"2026-10-16T16:29:22.321589Z","end":"2026-10-16T16:29:22.687165Z","steps":["trace[1726138640] 'process raft request' (duration: 365.045406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:29:22.687245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.51846ms","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-10-16T16:29:22.687272Z","caller":"traceutil/trace.go:171","msg":"trace[1402053125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"183.592202ms","start":"2026-10-16T16:29:22.503671Z","end":"2026-10-16T16:29:22.687264Z","steps":["trace[1402053125] 'agreement among raft nodes before linearized reading' (duration: 183.51854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:29:22.687751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:29:22.321568Z","time spent":"365.667583ms","remote":"127.0.0.1:33992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T16:29:23.352593Z","caller":"traceutil/trace.go:171","msg":"trace[799608966] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"354.476761ms","start":"2026-10-16T16:29:22.998082Z","end":"2026-10-16T16:29:23.352559Z","steps":["trace[799608966] 'read index received' (duration: 311.374612ms)","trace[799608966] 'applied index is now lower than readState.Index' (duration: 43.101289ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:29:23.352696Z","caller":"traceutil/trace.go:171","msg":"trace[1424139178] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"577.291165ms","start":"2026-10-16T16:29:22.775364Z","end":"2026-10-16T16:29:23.352655Z","steps":["trace[1424139178] 'process raft request' (duration: 534.099774ms)","trace[1424139178] 'compare' (duration: 42.77275ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T16:29:23.352868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:29:22.775345Z","time spent":"577.44366ms","remote":"127.0.0.1:60556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-16T16:29:23.352869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.773159ms","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-10-16T16:29:23.352937Z","caller":"traceutil/trace.go:171","msg":"trace[1487929996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"354.899853ms","start":"2026-10-16T16:29:22.998017Z","end":"2026-10-16T16:29:23.352917Z","steps":["trace[1487929996] 'agreement among raft nodes before linearized reading' (duration: 354.683277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:29:23.35298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:29:22.998004Z","time spent":"354.968354ms","remote":"127.0.0.1:60324","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-10-16T16:29:23.423607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.840213ms","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-10-16T16:29:23.423653Z","caller":"traceutil/trace.go:171","msg":"trace[1343754244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"395.918935ms","start":"2026-10-16T16:29:23.027721Z","end":"2026-10-16T16:29:23.42364Z","steps":["trace[1343754244] 'agreement among raft nodes before linearized reading' (duration: 395.183885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:29:23.42373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:29:23.027684Z","time spent":"396.034148ms","remote":"127.0.0.1:60324","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-10-16T16:29:39.824971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.830559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T16:29:39.825084Z","caller":"traceutil/trace.go:171","msg":"trace[782335942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"177.987524ms","start":"2026-10-16T16:29:39.647072Z","end":"2026-10-16T16:29:39.82506Z","steps":["trace[782335942] 'range keys from in-memory index tree' (duration: 177.762288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:29:39.825116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.694664ms","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-10-16T16:29:39.825185Z","caller":"traceutil/trace.go:171","msg":"trace[1624236958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"262.795547ms","start":"2026-10-16T16:29:39.562373Z","end":"2026-10-16T16:29:39.825168Z","steps":["trace[1624236958] 'range keys from in-memory index tree' (duration: 262.494098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:29:39.82538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.055518ms","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-10-16T16:29:39.825609Z","caller":"traceutil/trace.go:171","msg":"trace[210237379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:771; }","duration":"253.341627ms","start":"2026-10-16T16:29:39.572251Z","end":"2026-10-16T16:29:39.825593Z","steps":["trace[210237379] 'range keys from in-memory index tree' (duration: 252.558554ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:29:39.826241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.097716ms","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-10-16T16:29:39.826367Z","caller":"traceutil/trace.go:171","msg":"trace[1051538260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:771; }","duration":"267.557229ms","start":"2026-10-16T16:29:39.558791Z","end":"2026-10-16T16:29:39.826349Z","steps":["trace[1051538260] 'range keys from in-memory index tree' (duration: 266.078897ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:30:00.482016Z","caller":"traceutil/trace.go:171","msg":"trace[1982521347] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"127.262407ms","start":"2026-10-16T16:30:00.354726Z","end":"2026-10-16T16:30:00.481988Z","steps":["trace[1982521347] 'process raft request' (duration: 72.38008ms)","trace[1982521347] 'compare' (duration: 54.66411ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:30:05.810168Z","caller":"traceutil/trace.go:171","msg":"trace[74503889] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"118.437956ms","start":"2026-10-16T16:30:05.691703Z","end":"2026-10-16T16:30:05.810141Z","steps":["trace[74503889] 'process raft request' (duration: 104.860749ms)","trace[74503889] 'compare' (duration: 13.476454ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T16:30:12.481835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.392584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T16:30:12.482048Z","caller":"traceutil/trace.go:171","msg":"trace[1908865113] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"453.142436ms","start":"2026-10-16T16:30:12.028885Z","end":"2026-10-16T16:30:12.482027Z","steps":["trace[1908865113] 'process raft request' (duration: 453.055163ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:30:12.482067Z","caller":"traceutil/trace.go:171","msg":"trace[1568087373] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"453.680081ms","start":"2026-10-16T16:30:12.028355Z","end":"2026-10-16T16:30:12.482035Z","steps":["trace[1568087373] 'process raft request' (duration: 81.813655ms)","trace[1568087373] 'compare' (duration: 371.063615ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T16:30:12.482167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:30:12.028877Z","time spent":"453.245559ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T16:30:12.482184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:30:12.028344Z","time spent":"453.786214ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T16:31:04.923395Z","caller":"traceutil/trace.go:171","msg":"trace[451583164] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"125.861105ms","start":"2026-10-16T16:31:04.79751Z","end":"2026-10-16T16:31:04.923371Z","steps":["trace[451583164] 'read index received' (duration: 125.663239ms)","trace[451583164] 'applied index is now lower than readState.Index' (duration: 197.046µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:31:04.923849Z","caller":"traceutil/trace.go:171","msg":"trace[1294249233] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"141.649123ms","start":"2026-10-16T16:31:04.782172Z","end":"2026-10-16T16:31:04.923821Z","steps":["trace[1294249233] 'process raft request' (duration: 141.065837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:04.923923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.705277ms","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-10-16T16:31:04.923988Z","caller":"traceutil/trace.go:171","msg":"trace[316644787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1886; }","duration":"108.836021ms","start":"2026-10-16T16:31:04.815134Z","end":"2026-10-16T16:31:04.92397Z","steps":["trace[316644787] 'agreement among raft nodes before linearized reading' (duration: 108.647695ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:04.923865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.329038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9f345b33-72d7-484d-bc71-91aae28d85ce\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-16T16:31:04.92424Z","caller":"traceutil/trace.go:171","msg":"trace[890915420] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9f345b33-72d7-484d-bc71-91aae28d85ce; range_end:; response_count:1; response_revision:1886; }","duration":"126.742119ms","start":"2026-10-16T16:31:04.797474Z","end":"2026-10-16T16:31:04.924217Z","steps":["trace[890915420] 'agreement among raft nodes before linearized reading' (duration: 126.190834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:05.170405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.41867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T16:31:05.170538Z","caller":"traceutil/trace.go:171","msg":"trace[200852546] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"240.813485ms","start":"2026-10-16T16:31:04.9297Z","end":"2026-10-16T16:31:05.170513Z","steps":["trace[200852546] 'process raft request' (duration: 119.186089ms)","trace[200852546] 'compare' (duration: 121.182013ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:31:05.23573Z","caller":"traceutil/trace.go:171","msg":"trace[32141467] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"303.898602ms","start":"2026-10-16T16:31:04.931802Z","end":"2026-10-16T16:31:05.235701Z","steps":["trace[32141467] 'process raft request' (duration: 303.673426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:05.235941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:04.931786Z","time spent":"304.068347ms","remote":"127.0.0.1:34098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3726,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T16:31:05.629189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.284465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-16T16:31:05.62922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.750937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9f345b33-72d7-484d-bc71-91aae28d85ce\" ","response":"range_response_count:1 size:3741"} {"level":"info","ts":"2026-10-16T16:31:05.629267Z","caller":"traceutil/trace.go:171","msg":"trace[1008915980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1888; }","duration":"284.408858ms","start":"2026-10-16T16:31:05.344838Z","end":"2026-10-16T16:31:05.629247Z","steps":["trace[1008915980] 'range keys from in-memory index tree' (duration: 284.079059ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:05.62927Z","caller":"traceutil/trace.go:171","msg":"trace[1788248031] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9f345b33-72d7-484d-bc71-91aae28d85ce; range_end:; response_count:1; response_revision:1888; }","duration":"317.827528ms","start":"2026-10-16T16:31:05.311426Z","end":"2026-10-16T16:31:05.629254Z","steps":["trace[1788248031] 'range keys from in-memory index tree' (duration: 317.653514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:05.629303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:05.311406Z","time spent":"317.88832ms","remote":"127.0.0.1:34098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3764,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9f345b33-72d7-484d-bc71-91aae28d85ce\" "} {"level":"info","ts":"2026-10-16T16:31:05.670886Z","caller":"traceutil/trace.go:171","msg":"trace[1037551261] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"318.268451ms","start":"2026-10-16T16:31:05.352599Z","end":"2026-10-16T16:31:05.670867Z","steps":["trace[1037551261] 'process raft request' (duration: 318.132497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:05.671019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:05.352576Z","time spent":"318.364704ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T16:31:05.675413Z","caller":"traceutil/trace.go:171","msg":"trace[1851903646] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"321.555333ms","start":"2026-10-16T16:31:05.353831Z","end":"2026-10-16T16:31:05.675387Z","steps":["trace[1851903646] 'process raft request' (duration: 321.386939ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:05.6756Z","caller":"traceutil/trace.go:171","msg":"trace[445848219] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"238.500261ms","start":"2026-10-16T16:31:05.437084Z","end":"2026-10-16T16:31:05.675584Z","steps":["trace[445848219] 'process raft request' (duration: 238.264084ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:05.675701Z","caller":"traceutil/trace.go:171","msg":"trace[1937133650] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1963; }","duration":"113.10036ms","start":"2026-10-16T16:31:05.562583Z","end":"2026-10-16T16:31:05.675684Z","steps":["trace[1937133650] 'read index received' (duration: 108.177212ms)","trace[1937133650] 'applied index is now lower than readState.Index' (duration: 4.921308ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T16:31:05.67571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:05.353811Z","time spent":"321.681826ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T16:31:05.675946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.359557ms","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-10-16T16:31:05.675991Z","caller":"traceutil/trace.go:171","msg":"trace[1500900613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1893; }","duration":"113.437659ms","start":"2026-10-16T16:31:05.562541Z","end":"2026-10-16T16:31:05.675979Z","steps":["trace[1500900613] 'agreement among raft nodes before linearized reading' (duration: 113.300445ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:05.676072Z","caller":"traceutil/trace.go:171","msg":"trace[1619736221] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"121.455472ms","start":"2026-10-16T16:31:05.554608Z","end":"2026-10-16T16:31:05.676064Z","steps":["trace[1619736221] 'process raft request' (duration: 120.864716ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:18.206118Z","caller":"traceutil/trace.go:171","msg":"trace[87048462] linearizableReadLoop","detail":"{readStateIndex:2080; appliedIndex:2076; }","duration":"458.288828ms","start":"2026-10-16T16:31:17.747809Z","end":"2026-10-16T16:31:18.206097Z","steps":["trace[87048462] 'read index received' (duration: 43.915913ms)","trace[87048462] 'applied index is now lower than readState.Index' (duration: 414.371895ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:31:18.20615Z","caller":"traceutil/trace.go:171","msg":"trace[68475557] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"484.340334ms","start":"2026-10-16T16:31:17.721777Z","end":"2026-10-16T16:31:18.206117Z","steps":["trace[68475557] 'process raft request' (duration: 484.255642ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:18.206216Z","caller":"traceutil/trace.go:171","msg":"trace[112333243] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"437.58203ms","start":"2026-10-16T16:31:17.768623Z","end":"2026-10-16T16:31:18.206205Z","steps":["trace[112333243] 'process raft request' (duration: 437.440096ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:18.206222Z","caller":"traceutil/trace.go:171","msg":"trace[259615742] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"486.066113ms","start":"2026-10-16T16:31:17.720051Z","end":"2026-10-16T16:31:18.206117Z","steps":["trace[259615742] 'process raft request' (duration: 485.93568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:18.206323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:17.721764Z","time spent":"484.465566ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T16:31:18.20634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:17.720037Z","time spent":"486.258257ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T16:31:18.206431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:17.768596Z","time spent":"437.64661ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T16:31:18.206739Z","caller":"traceutil/trace.go:171","msg":"trace[1252997991] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"487.296519ms","start":"2026-10-16T16:31:17.719418Z","end":"2026-10-16T16:31:18.206715Z","steps":["trace[1252997991] 'process raft request' (duration: 479.851043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:18.206966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:17.719395Z","time spent":"487.458002ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T16:31:18.207213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.396112ms","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-10-16T16:31:18.207259Z","caller":"traceutil/trace.go:171","msg":"trace[1046582822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2002; }","duration":"459.472003ms","start":"2026-10-16T16:31:17.747775Z","end":"2026-10-16T16:31:18.207247Z","steps":["trace[1046582822] 'agreement among raft nodes before linearized reading' (duration: 458.941502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:18.207287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:17.747761Z","time spent":"459.520104ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-16T16:31:18.220572Z","caller":"traceutil/trace.go:171","msg":"trace[1034654372] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"427.743253ms","start":"2026-10-16T16:31:17.792811Z","end":"2026-10-16T16:31:18.220554Z","steps":["trace[1034654372] 'process raft request' (duration: 427.61448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:18.220807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:17.792788Z","time spent":"427.937177ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T16:31:18.221064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.251875ms","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-10-16T16:31:18.221027Z","caller":"traceutil/trace.go:171","msg":"trace[1000893166] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"239.16763ms","start":"2026-10-16T16:31:17.981795Z","end":"2026-10-16T16:31:18.220963Z","steps":["trace[1000893166] 'process raft request' (duration: 238.725551ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:18.221117Z","caller":"traceutil/trace.go:171","msg":"trace[1388049436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2004; }","duration":"462.323496ms","start":"2026-10-16T16:31:17.758781Z","end":"2026-10-16T16:31:18.221104Z","steps":["trace[1388049436] 'agreement among raft nodes before linearized reading' (duration: 462.105032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:18.221154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:31:17.75877Z","time spent":"462.376197ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-16T16:31:18.221213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.582381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12342"} {"level":"info","ts":"2026-10-16T16:31:18.221299Z","caller":"traceutil/trace.go:171","msg":"trace[2029874496] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2004; }","duration":"138.794426ms","start":"2026-10-16T16:31:18.082483Z","end":"2026-10-16T16:31:18.221278Z","steps":["trace[2029874496] 'agreement among raft nodes before linearized reading' (duration: 138.498399ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:19.023358Z","caller":"traceutil/trace.go:171","msg":"trace[957182438] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"112.787981ms","start":"2026-10-16T16:31:18.910537Z","end":"2026-10-16T16:31:19.023325Z","steps":["trace[957182438] 'process raft request' (duration: 112.574927ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:44.495889Z","caller":"traceutil/trace.go:171","msg":"trace[717209752] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2232; }","duration":"138.117503ms","start":"2026-10-16T16:31:44.35775Z","end":"2026-10-16T16:31:44.495868Z","steps":["trace[717209752] 'read index received' (duration: 137.877217ms)","trace[717209752] 'applied index is now lower than readState.Index' (duration: 239.156µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:31:44.496134Z","caller":"traceutil/trace.go:171","msg":"trace[1784904327] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"179.607472ms","start":"2026-10-16T16:31:44.316514Z","end":"2026-10-16T16:31:44.496121Z","steps":["trace[1784904327] 'process raft request' (duration: 179.182032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:44.496375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.902488ms","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-10-16T16:31:44.496419Z","caller":"traceutil/trace.go:171","msg":"trace[33953133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2151; }","duration":"121.982829ms","start":"2026-10-16T16:31:44.374424Z","end":"2026-10-16T16:31:44.496407Z","steps":["trace[33953133] 'agreement among raft nodes before linearized reading' (duration: 121.871747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:31:44.49637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.601734ms","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-10-16T16:31:44.496532Z","caller":"traceutil/trace.go:171","msg":"trace[1457217766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2151; }","duration":"138.814379ms","start":"2026-10-16T16:31:44.357699Z","end":"2026-10-16T16:31:44.496513Z","steps":["trace[1457217766] 'agreement among raft nodes before linearized reading' (duration: 138.520452ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:44.496646Z","caller":"traceutil/trace.go:171","msg":"trace[963609467] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"177.235576ms","start":"2026-10-16T16:31:44.319391Z","end":"2026-10-16T16:31:44.496626Z","steps":["trace[963609467] 'process raft request' (duration: 176.447358ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:44.496542Z","caller":"traceutil/trace.go:171","msg":"trace[1024196612] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"178.972687ms","start":"2026-10-16T16:31:44.317549Z","end":"2026-10-16T16:31:44.496522Z","steps":["trace[1024196612] 'process raft request' (duration: 178.2543ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:31:44.7143Z","caller":"traceutil/trace.go:171","msg":"trace[1469633282] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"129.634337ms","start":"2026-10-16T16:31:44.584632Z","end":"2026-10-16T16:31:44.714266Z","steps":["trace[1469633282] 'process raft request' (duration: 88.918696ms)","trace[1469633282] 'compare' (duration: 40.569627ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T16:31:45.768744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.940647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T16:31:45.76885Z","caller":"traceutil/trace.go:171","msg":"trace[1603551820] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2156; }","duration":"130.115701ms","start":"2026-10-16T16:31:45.638709Z","end":"2026-10-16T16:31:45.768824Z","steps":["trace[1603551820] 'count revisions from in-memory index tree' (duration: 129.819444ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:33:06.802142Z","caller":"traceutil/trace.go:171","msg":"trace[323992950] linearizableReadLoop","detail":"{readStateIndex:2733; appliedIndex:2732; }","duration":"154.829966ms","start":"2026-10-16T16:33:06.64729Z","end":"2026-10-16T16:33:06.802119Z","steps":["trace[323992950] 'read index received' (duration: 154.690723ms)","trace[323992950] 'applied index is now lower than readState.Index' (duration: 138.093µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:33:06.802201Z","caller":"traceutil/trace.go:171","msg":"trace[1539222118] transaction","detail":"{read_only:false; response_revision:2630; number_of_response:1; }","duration":"160.179671ms","start":"2026-10-16T16:33:06.641988Z","end":"2026-10-16T16:33:06.802168Z","steps":["trace[1539222118] 'process raft request' (duration: 159.992777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:33:06.802263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.954569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T16:33:06.802295Z","caller":"traceutil/trace.go:171","msg":"trace[515368389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2630; }","duration":"155.039101ms","start":"2026-10-16T16:33:06.647246Z","end":"2026-10-16T16:33:06.802285Z","steps":["trace[515368389] 'agreement among raft nodes before linearized reading' (duration: 154.97184ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:33:48.347Z","caller":"traceutil/trace.go:171","msg":"trace[1911480432] transaction","detail":"{read_only:false; response_revision:2916; number_of_response:1; }","duration":"175.482464ms","start":"2026-10-16T16:33:48.171491Z","end":"2026-10-16T16:33:48.346973Z","steps":["trace[1911480432] 'process raft request' (duration: 175.35125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:16.775047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.19555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-10-16T16:38:16.77513Z","caller":"traceutil/trace.go:171","msg":"trace[251542881] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4945; }","duration":"106.305382ms","start":"2026-10-16T16:38:16.668805Z","end":"2026-10-16T16:38:16.775111Z","steps":["trace[251542881] 'range keys from in-memory index tree' (duration: 106.086877ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:38:24.822601Z","caller":"traceutil/trace.go:171","msg":"trace[1870540096] linearizableReadLoop","detail":"{readStateIndex:5322; appliedIndex:5321; }","duration":"174.994098ms","start":"2026-10-16T16:38:24.647587Z","end":"2026-10-16T16:38:24.822581Z","steps":["trace[1870540096] 'read index received' (duration: 174.884505ms)","trace[1870540096] 'applied index is now lower than readState.Index' (duration: 108.883µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T16:38:24.822735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.132021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T16:38:24.822716Z","caller":"traceutil/trace.go:171","msg":"trace[707753489] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"186.305774ms","start":"2026-10-16T16:38:24.63637Z","end":"2026-10-16T16:38:24.822676Z","steps":["trace[707753489] 'process raft request' (duration: 186.086468ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:24.822838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.274456ms","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-10-16T16:38:24.822765Z","caller":"traceutil/trace.go:171","msg":"trace[1463676868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5125; }","duration":"175.194724ms","start":"2026-10-16T16:38:24.647562Z","end":"2026-10-16T16:38:24.822757Z","steps":["trace[1463676868] 'agreement among raft nodes before linearized reading' (duration: 175.135692ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:38:24.822858Z","caller":"traceutil/trace.go:171","msg":"trace[1766613277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5125; }","duration":"129.320297ms","start":"2026-10-16T16:38:24.693534Z","end":"2026-10-16T16:38:24.822854Z","steps":["trace[1766613277] 'agreement among raft nodes before linearized reading' (duration: 129.243396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:24.82297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.027388ms","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-10-16T16:38:24.822988Z","caller":"traceutil/trace.go:171","msg":"trace[980545779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5125; }","duration":"126.06518ms","start":"2026-10-16T16:38:24.696918Z","end":"2026-10-16T16:38:24.822984Z","steps":["trace[980545779] 'agreement among raft nodes before linearized reading' (duration: 126.021999ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:38:24.941291Z","caller":"traceutil/trace.go:171","msg":"trace[1755897132] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"114.785783ms","start":"2026-10-16T16:38:24.826467Z","end":"2026-10-16T16:38:24.941253Z","steps":["trace[1755897132] 'process raft request' (duration: 67.063809ms)","trace[1755897132] 'compare' (duration: 47.531079ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:38:24.941664Z","caller":"traceutil/trace.go:171","msg":"trace[222200622] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"112.891293ms","start":"2026-10-16T16:38:24.828756Z","end":"2026-10-16T16:38:24.941647Z","steps":["trace[222200622] 'process raft request' (duration: 112.431521ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:38:34.349991Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2843} {"level":"info","ts":"2026-10-16T16:38:34.423341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2843,"took":"72.057006ms","hash":178334284,"current-db-size-bytes":51048448,"current-db-size":"51 MB","current-db-size-in-use-bytes":27070464,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T16:38:34.423419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":178334284,"revision":2843,"compact-revision":-1} {"level":"warn","ts":"2026-10-16T16:38:40.79226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.777947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T16:38:40.792364Z","caller":"traceutil/trace.go:171","msg":"trace[1449781421] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5330; }","duration":"615.947421ms","start":"2026-10-16T16:38:40.176392Z","end":"2026-10-16T16:38:40.792339Z","steps":["trace[1449781421] 'count revisions from in-memory index tree' (duration: 615.655704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:40.792411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:38:40.176375Z","time spent":"616.023174ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-10-16T16:38:40.792625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.384737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T16:38:40.792809Z","caller":"traceutil/trace.go:171","msg":"trace[1176854351] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"572.946714ms","start":"2026-10-16T16:38:40.219825Z","end":"2026-10-16T16:38:40.792772Z","steps":["trace[1176854351] 'process raft request' (duration: 157.32403ms)","trace[1176854351] 'compare' (duration: 414.902054ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T16:38:40.792879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:38:40.219795Z","time spent":"573.052356ms","remote":"127.0.0.1:60324","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-10-16T16:38:40.793052Z","caller":"traceutil/trace.go:171","msg":"trace[352266564] linearizableReadLoop","detail":"{readStateIndex:5536; appliedIndex:5534; }","duration":"350.666881ms","start":"2026-10-16T16:38:40.44234Z","end":"2026-10-16T16:38:40.793007Z","steps":["trace[352266564] 'read index received' (duration: 349.354454ms)","trace[352266564] 'applied index is now lower than readState.Index' (duration: 1.310857ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:38:40.793406Z","caller":"traceutil/trace.go:171","msg":"trace[248246842] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"572.830022ms","start":"2026-10-16T16:38:40.220556Z","end":"2026-10-16T16:38:40.793387Z","steps":["trace[248246842] 'process raft request' (duration: 572.166574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:40.793567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:38:40.220543Z","time spent":"572.931734ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T16:38:40.793718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.209915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T16:38:40.793764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.843944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T16:38:40.793798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.258231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3388"} {"level":"info","ts":"2026-10-16T16:38:40.793844Z","caller":"traceutil/trace.go:171","msg":"trace[287584805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5332; }","duration":"144.964186ms","start":"2026-10-16T16:38:40.64886Z","end":"2026-10-16T16:38:40.793824Z","steps":["trace[287584805] 'agreement among raft nodes before linearized reading' (duration: 144.828112ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:38:40.793853Z","caller":"traceutil/trace.go:171","msg":"trace[985198330] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5332; }","duration":"118.349253ms","start":"2026-10-16T16:38:40.675487Z","end":"2026-10-16T16:38:40.793836Z","steps":["trace[985198330] 'agreement among raft nodes before linearized reading' (duration: 118.196799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:40.793858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.071072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T16:38:40.793905Z","caller":"traceutil/trace.go:171","msg":"trace[563270486] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5332; }","duration":"193.141074ms","start":"2026-10-16T16:38:40.600749Z","end":"2026-10-16T16:38:40.79389Z","steps":["trace[563270486] 'agreement among raft nodes before linearized reading' (duration: 193.066632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:40.793967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.619837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-10-16T16:38:40.794008Z","caller":"traceutil/trace.go:171","msg":"trace[1077026977] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5332; }","duration":"351.694979ms","start":"2026-10-16T16:38:40.4423Z","end":"2026-10-16T16:38:40.793995Z","steps":["trace[1077026977] 'agreement among raft nodes before linearized reading' (duration: 351.596546ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:40.794035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T16:38:40.44228Z","time spent":"351.7478ms","remote":"127.0.0.1:34104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2013,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-10-16T16:38:40.793787Z","caller":"traceutil/trace.go:171","msg":"trace[1154777450] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:5332; }","duration":"295.301167ms","start":"2026-10-16T16:38:40.498466Z","end":"2026-10-16T16:38:40.793767Z","steps":["trace[1154777450] 'agreement among raft nodes before linearized reading' (duration: 295.164744ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:38:41.002428Z","caller":"traceutil/trace.go:171","msg":"trace[73804151] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"159.514631ms","start":"2026-10-16T16:38:40.842886Z","end":"2026-10-16T16:38:41.0024Z","steps":["trace[73804151] 'process raft request' (duration: 159.325176ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:38:41.002591Z","caller":"traceutil/trace.go:171","msg":"trace[1010549488] linearizableReadLoop","detail":"{readStateIndex:5540; appliedIndex:5539; }","duration":"155.482601ms","start":"2026-10-16T16:38:40.847057Z","end":"2026-10-16T16:38:41.002539Z","steps":["trace[1010549488] 'read index received' (duration: 155.087ms)","trace[1010549488] 'applied index is now lower than readState.Index' (duration: 393.221µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T16:38:41.002901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.82274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18df0ff0cc3fe518\" ","response":"range_response_count:1 size:746"} {"level":"warn","ts":"2026-10-16T16:38:41.00295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.124172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"info","ts":"2026-10-16T16:38:41.002972Z","caller":"traceutil/trace.go:171","msg":"trace[1352122661] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18df0ff0cc3fe518; range_end:; response_count:1; response_revision:5336; }","duration":"155.938233ms","start":"2026-10-16T16:38:40.847019Z","end":"2026-10-16T16:38:41.002957Z","steps":["trace[1352122661] 'agreement among raft nodes before linearized reading' (duration: 155.679026ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:38:41.002989Z","caller":"traceutil/trace.go:171","msg":"trace[1683744364] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5336; }","duration":"155.211854ms","start":"2026-10-16T16:38:40.847766Z","end":"2026-10-16T16:38:41.002978Z","steps":["trace[1683744364] 'agreement among raft nodes before linearized reading' (duration: 155.045989ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:38:41.167197Z","caller":"traceutil/trace.go:171","msg":"trace[391100703] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"106.856142ms","start":"2026-10-16T16:38:41.0603Z","end":"2026-10-16T16:38:41.167156Z","steps":["trace[391100703] 'process raft request' (duration: 65.427807ms)","trace[391100703] 'compare' (duration: 41.25926ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T16:38:41.167418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.968187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18df0ff0cc96acbc\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-10-16T16:38:41.167513Z","caller":"traceutil/trace.go:171","msg":"trace[1615515186] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18df0ff0cc96acbc; range_end:; response_count:1; response_revision:5339; }","duration":"106.160282ms","start":"2026-10-16T16:38:41.061326Z","end":"2026-10-16T16:38:41.167487Z","steps":["trace[1615515186] 'agreement among raft nodes before linearized reading' (duration: 105.863344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:41.443683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.256317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T16:38:41.443967Z","caller":"traceutil/trace.go:171","msg":"trace[1399233474] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"254.742426ms","start":"2026-10-16T16:38:41.189167Z","end":"2026-10-16T16:38:41.443909Z","steps":["trace[1399233474] 'process raft request' (duration: 117.11285ms)","trace[1399233474] 'compare' (duration: 137.091102ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:38:41.444118Z","caller":"traceutil/trace.go:171","msg":"trace[1980346555] linearizableReadLoop","detail":"{readStateIndex:5547; appliedIndex:5546; }","duration":"253.03251ms","start":"2026-10-16T16:38:41.191056Z","end":"2026-10-16T16:38:41.444088Z","steps":["trace[1980346555] 'read index received' (duration: 115.313471ms)","trace[1980346555] 'applied index is now lower than readState.Index' (duration: 137.715718ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T16:38:41.444225Z","caller":"traceutil/trace.go:171","msg":"trace[376227243] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"211.79493ms","start":"2026-10-16T16:38:41.23238Z","end":"2026-10-16T16:38:41.444175Z","steps":["trace[376227243] 'process raft request' (duration: 211.41828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:41.444421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.553179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18df0ff0d0117f9c\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-10-16T16:38:41.444479Z","caller":"traceutil/trace.go:171","msg":"trace[703120674] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18df0ff0d0117f9c; range_end:; response_count:1; response_revision:5344; }","duration":"167.657712ms","start":"2026-10-16T16:38:41.276804Z","end":"2026-10-16T16:38:41.444462Z","steps":["trace[703120674] 'agreement among raft nodes before linearized reading' (duration: 167.444396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:38:41.444552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.448791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-16T16:38:41.444686Z","caller":"traceutil/trace.go:171","msg":"trace[108526115] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5344; }","duration":"253.660687ms","start":"2026-10-16T16:38:41.190978Z","end":"2026-10-16T16:38:41.444639Z","steps":["trace[108526115] 'agreement among raft nodes before linearized reading' (duration: 253.185724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T16:42:33.10292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.755288ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-10-16T16:42:33.147048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.952614ms","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-10-16T16:42:33.147127Z","caller":"traceutil/trace.go:171","msg":"trace[355638886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6984; }","duration":"109.080947ms","start":"2026-10-16T16:42:33.038027Z","end":"2026-10-16T16:42:33.147108Z","steps":["trace[355638886] 'agreement among raft nodes before linearized reading' (duration: 103.972777ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T16:43:34.357066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5276} {"level":"info","ts":"2026-10-16T16:43:34.53036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5276,"took":"171.823628ms","hash":682812508,"current-db-size-bytes":51048448,"current-db-size":"51 MB","current-db-size-in-use-bytes":18919424,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-16T16:43:34.530424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":682812508,"revision":5276,"compact-revision":2843}