{"level":"warn","ts":"2026-05-25T17:46:54.543311Z","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-25T17:46:54.543422Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-25T17:46:54.543506Z","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-25T17:46:54.543523Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-05-25T17:46:54.543564Z","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-25T17:46:54.544251Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-05-25T17:46:54.54434Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-25T17:46:54.550109Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.488262ms"} {"level":"info","ts":"2026-05-25T17:46:54.561064Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-05-25T17:46:54.561268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T17:46:54.561312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-05-25T17:46:54.561351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T17:46:54.561371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-05-25T17:46:54.561448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-05-25T17:46:54.566427Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T17:46:54.567742Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T17:46:54.568649Z","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-25T17:46:54.570924Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T17:46:54.572032Z","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-25T17:46:54.572141Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T17:46:54.573476Z","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-25T17:46:54.573587Z","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-25T17:46:54.573596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-05-25T17:46:54.573911Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-05-25T17:46:54.576385Z","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-25T17:46:54.576781Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T17:46:54.57682Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T17:46:54.576639Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-05-25T17:46:54.576849Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-05-25T17:46:54.962222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T17:46:54.962291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T17:46:54.962312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-05-25T17:46:54.962325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-05-25T17:46:54.962332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-05-25T17:46:54.962343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-05-25T17:46:54.962608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-05-25T17:46:54.963439Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:46:54.964323Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T17:46:54.964403Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T17:46:54.96434Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T17:46:54.966843Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T17:46:54.966879Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T17:46:54.967714Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:46:54.967805Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:46:54.967935Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:46:54.968677Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T17:46:54.974851Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-05-25T17:47:01.042842Z","caller":"traceutil/trace.go:171","msg":"trace[169130749] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"417.996591ms","start":"2026-05-25T17:47:00.62482Z","end":"2026-05-25T17:47:01.042816Z","steps":["trace[169130749] 'process raft request' (duration: 417.816116ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:47:01.042922Z","caller":"traceutil/trace.go:171","msg":"trace[1735812213] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:314; }","duration":"391.810562ms","start":"2026-05-25T17:47:00.651015Z","end":"2026-05-25T17:47:01.042826Z","steps":["trace[1735812213] 'read index received' (duration: 391.786472ms)","trace[1735812213] 'applied index is now lower than readState.Index' (duration: 20.01µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:47:01.043168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.06976ms","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-25T17:47:01.043294Z","caller":"traceutil/trace.go:171","msg":"trace[1278297620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:305; }","duration":"392.295487ms","start":"2026-05-25T17:47:00.650973Z","end":"2026-05-25T17:47:01.043269Z","steps":["trace[1278297620] 'agreement among raft nodes before linearized reading' (duration: 391.985828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:01.043355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:00.650957Z","time spent":"392.388521ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T17:47:01.043517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:00.624793Z","time spent":"418.220389ms","remote":"127.0.0.1:40826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T17:47:01.32583Z","caller":"traceutil/trace.go:171","msg":"trace[559391625] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"609.804574ms","start":"2026-05-25T17:47:00.716Z","end":"2026-05-25T17:47:01.325804Z","steps":["trace[559391625] 'process raft request' (duration: 609.774114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:01.325984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:00.715961Z","time spent":"609.957839ms","remote":"127.0.0.1:40820","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T17:47:01.326274Z","caller":"traceutil/trace.go:171","msg":"trace[820512767] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"611.747814ms","start":"2026-05-25T17:47:00.714517Z","end":"2026-05-25T17:47:01.326265Z","steps":["trace[820512767] 'process raft request' (duration: 611.112174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:01.326329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:00.714491Z","time spent":"611.816367ms","remote":"127.0.0.1:40820","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T17:47:01.326441Z","caller":"traceutil/trace.go:171","msg":"trace[1625311614] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"611.357932ms","start":"2026-05-25T17:47:00.715075Z","end":"2026-05-25T17:47:01.326433Z","steps":["trace[1625311614] 'process raft request' (duration: 610.63854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:01.326498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:00.715058Z","time spent":"611.413083ms","remote":"127.0.0.1:40820","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T17:47:01.326611Z","caller":"traceutil/trace.go:171","msg":"trace[961960062] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"611.386063ms","start":"2026-05-25T17:47:00.715218Z","end":"2026-05-25T17:47:01.326604Z","steps":["trace[961960062] 'process raft request' (duration: 610.530347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:01.326657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:00.715162Z","time spent":"611.477116ms","remote":"127.0.0.1:40820","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T17:47:01.327129Z","caller":"traceutil/trace.go:171","msg":"trace[2020433002] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:318; }","duration":"284.044601ms","start":"2026-05-25T17:47:01.043054Z","end":"2026-05-25T17:47:01.327099Z","steps":["trace[2020433002] 'read index received' (duration: 284.037801ms)","trace[2020433002] 'applied index is now lower than readState.Index' (duration: 5.13µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:47:01.327307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.679007ms","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-25T17:47:01.327351Z","caller":"traceutil/trace.go:171","msg":"trace[1975907337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:305; }","duration":"399.788092ms","start":"2026-05-25T17:47:00.927552Z","end":"2026-05-25T17:47:01.32734Z","steps":["trace[1975907337] 'agreement among raft nodes before linearized reading' (duration: 399.69633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:01.327381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:00.92753Z","time spent":"399.844733ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T17:47:01.327552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.084866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-25T17:47:01.327587Z","caller":"traceutil/trace.go:171","msg":"trace[1855373106] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:305; }","duration":"613.182068ms","start":"2026-05-25T17:47:00.714397Z","end":"2026-05-25T17:47:01.327579Z","steps":["trace[1855373106] 'agreement among raft nodes before linearized reading' (duration: 613.124247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:01.327613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:00.714363Z","time spent":"613.244721ms","remote":"127.0.0.1:40820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-05-25T17:47:01.344762Z","caller":"traceutil/trace.go:171","msg":"trace[1682579511] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"297.895448ms","start":"2026-05-25T17:47:01.046846Z","end":"2026-05-25T17:47:01.344741Z","steps":["trace[1682579511] 'process raft request' (duration: 297.491945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:01.344805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.577478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:47:01.34495Z","caller":"traceutil/trace.go:171","msg":"trace[122627486] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:306; }","duration":"297.797685ms","start":"2026-05-25T17:47:01.047116Z","end":"2026-05-25T17:47:01.344914Z","steps":["trace[122627486] 'agreement among raft nodes before linearized reading' (duration: 297.518316ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:47:05.426258Z","caller":"traceutil/trace.go:171","msg":"trace[415212899] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"112.888622ms","start":"2026-05-25T17:47:05.313345Z","end":"2026-05-25T17:47:05.426234Z","steps":["trace[415212899] 'process raft request' (duration: 112.774959ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:47:05.426441Z","caller":"traceutil/trace.go:171","msg":"trace[1771771807] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:384; }","duration":"102.453603ms","start":"2026-05-25T17:47:05.323976Z","end":"2026-05-25T17:47:05.426429Z","steps":["trace[1771771807] 'read index received' (duration: 162.155µs)","trace[1771771807] 'applied index is now lower than readState.Index' (duration: 102.290468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:47:05.426851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.859846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-25T17:47:05.426955Z","caller":"traceutil/trace.go:171","msg":"trace[1684746315] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:375; }","duration":"102.98908ms","start":"2026-05-25T17:47:05.323932Z","end":"2026-05-25T17:47:05.426921Z","steps":["trace[1684746315] 'agreement among raft nodes before linearized reading' (duration: 102.570607ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:47:05.433449Z","caller":"traceutil/trace.go:171","msg":"trace[1927969704] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"114.078169ms","start":"2026-05-25T17:47:05.312456Z","end":"2026-05-25T17:47:05.426534Z","steps":["trace[1927969704] 'process raft request' (duration: 75.721596ms)","trace[1927969704] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/kube-system/coredns-7c96b6546b-xtdl8.18b2e01eb840bf04; req_size:827; } (duration: 37.813487ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:47:36.684486Z","caller":"traceutil/trace.go:171","msg":"trace[1393501031] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"492.994803ms","start":"2026-05-25T17:47:36.191465Z","end":"2026-05-25T17:47:36.68446Z","steps":["trace[1393501031] 'read index received' (duration: 490.830998ms)","trace[1393501031] 'applied index is now lower than readState.Index' (duration: 2.162735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:47:36.684809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.466907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:47:36.684909Z","caller":"traceutil/trace.go:171","msg":"trace[1985826063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"256.611769ms","start":"2026-05-25T17:47:36.428272Z","end":"2026-05-25T17:47:36.684884Z","steps":["trace[1985826063] 'agreement among raft nodes before linearized reading' (duration: 256.475366ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:47:36.6849Z","caller":"traceutil/trace.go:171","msg":"trace[1192182684] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"510.590055ms","start":"2026-05-25T17:47:36.174261Z","end":"2026-05-25T17:47:36.684851Z","steps":["trace[1192182684] 'process raft request' (duration: 508.750685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:36.684815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.334939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-25T17:47:36.685174Z","caller":"traceutil/trace.go:171","msg":"trace[949755355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"493.719473ms","start":"2026-05-25T17:47:36.191401Z","end":"2026-05-25T17:47:36.685121Z","steps":["trace[949755355] 'agreement among raft nodes before linearized reading' (duration: 493.289216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:36.685345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:36.191377Z","time spent":"493.914367ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T17:47:36.685322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:36.174146Z","time spent":"510.861259ms","remote":"127.0.0.1:40702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T17:47:38.039764Z","caller":"traceutil/trace.go:171","msg":"trace[1065769800] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"119.161745ms","start":"2026-05-25T17:47:37.920555Z","end":"2026-05-25T17:47:38.039717Z","steps":["trace[1065769800] 'process raft request' (duration: 95.589107ms)","trace[1065769800] 'compare' (duration: 21.345341ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:47:38.039863Z","caller":"traceutil/trace.go:171","msg":"trace[447906501] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"119.126085ms","start":"2026-05-25T17:47:37.920707Z","end":"2026-05-25T17:47:38.039833Z","steps":["trace[447906501] 'read index received' (duration: 95.700708ms)","trace[447906501] 'applied index is now lower than readState.Index' (duration: 23.422337ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:47:38.040079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.363459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:47:38.040117Z","caller":"traceutil/trace.go:171","msg":"trace[2040006301] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:657; }","duration":"119.42901ms","start":"2026-05-25T17:47:37.920675Z","end":"2026-05-25T17:47:38.040104Z","steps":["trace[2040006301] 'agreement among raft nodes before linearized reading' (duration: 119.249547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:38.041261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.940438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:47:38.041402Z","caller":"traceutil/trace.go:171","msg":"trace[1866533937] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:657; }","duration":"120.663351ms","start":"2026-05-25T17:47:37.920696Z","end":"2026-05-25T17:47:38.041359Z","steps":["trace[1866533937] 'agreement among raft nodes before linearized reading' (duration: 119.275718ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:47:38.044377Z","caller":"traceutil/trace.go:171","msg":"trace[1676922265] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"123.157271ms","start":"2026-05-25T17:47:37.921173Z","end":"2026-05-25T17:47:38.044331Z","steps":["trace[1676922265] 'process raft request' (duration: 123.10772ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:47:38.04445Z","caller":"traceutil/trace.go:171","msg":"trace[835910240] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"123.629559ms","start":"2026-05-25T17:47:37.920778Z","end":"2026-05-25T17:47:38.044407Z","steps":["trace[835910240] 'process raft request' (duration: 123.402126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:47:50.864154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.390129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:47:50.864265Z","caller":"traceutil/trace.go:171","msg":"trace[139964656] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"106.686676ms","start":"2026-05-25T17:47:50.757561Z","end":"2026-05-25T17:47:50.864248Z","steps":["trace[139964656] 'read index received' (duration: 40.741µs)","trace[139964656] 'applied index is now lower than readState.Index' (duration: 106.644105ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:47:50.864355Z","caller":"traceutil/trace.go:171","msg":"trace[806867429] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"309.152317ms","start":"2026-05-25T17:47:50.555192Z","end":"2026-05-25T17:47:50.864344Z","steps":["trace[806867429] 'process raft request' (duration: 112.33633ms)","trace[806867429] 'compare' (duration: 196.186645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:47:50.864422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:47:50.555162Z","time spent":"309.225348ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:47:50.864866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.313076ms","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-25T17:47:50.864904Z","caller":"traceutil/trace.go:171","msg":"trace[1142585212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:790; }","duration":"107.398058ms","start":"2026-05-25T17:47:50.757496Z","end":"2026-05-25T17:47:50.864894Z","steps":["trace[1142585212] 'agreement among raft nodes before linearized reading' (duration: 107.042642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:49:14.375415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.570884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:49:14.375537Z","caller":"traceutil/trace.go:171","msg":"trace[1805791389] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1971; }","duration":"278.238173ms","start":"2026-05-25T17:49:14.097283Z","end":"2026-05-25T17:49:14.375521Z","steps":["trace[1805791389] 'read index received' (duration: 57.463µs)","trace[1805791389] 'applied index is now lower than readState.Index' (duration: 278.17921ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:49:14.375688Z","caller":"traceutil/trace.go:171","msg":"trace[99393196] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"379.244605ms","start":"2026-05-25T17:49:13.996408Z","end":"2026-05-25T17:49:14.375653Z","steps":["trace[99393196] 'process raft request' (duration: 85.351093ms)","trace[99393196] 'compare' (duration: 293.229555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:49:14.375767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.484018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T17:49:14.375898Z","caller":"traceutil/trace.go:171","msg":"trace[1564181593] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1898; }","duration":"278.650302ms","start":"2026-05-25T17:49:14.097233Z","end":"2026-05-25T17:49:14.375883Z","steps":["trace[1564181593] 'agreement among raft nodes before linearized reading' (duration: 278.488599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:49:14.376675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:49:13.996393Z","time spent":"379.43408ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:49:14.394304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.039627ms","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-25T17:49:14.39436Z","caller":"traceutil/trace.go:171","msg":"trace[346480790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1899; }","duration":"234.11866ms","start":"2026-05-25T17:49:14.160228Z","end":"2026-05-25T17:49:14.394347Z","steps":["trace[346480790] 'agreement among raft nodes before linearized reading' (duration: 233.958455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:49:14.394445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.269779ms","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-25T17:49:14.394529Z","caller":"traceutil/trace.go:171","msg":"trace[1833015265] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1899; }","duration":"149.389842ms","start":"2026-05-25T17:49:14.245117Z","end":"2026-05-25T17:49:14.394507Z","steps":["trace[1833015265] 'agreement among raft nodes before linearized reading' (duration: 149.184158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:49:14.394601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.896704ms","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-25T17:49:14.394691Z","caller":"traceutil/trace.go:171","msg":"trace[2127961935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1899; }","duration":"232.025757ms","start":"2026-05-25T17:49:14.162644Z","end":"2026-05-25T17:49:14.394669Z","steps":["trace[2127961935] 'agreement among raft nodes before linearized reading' (duration: 231.772821ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:49:14.394332Z","caller":"traceutil/trace.go:171","msg":"trace[310367373] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"148.950802ms","start":"2026-05-25T17:49:14.24533Z","end":"2026-05-25T17:49:14.394281Z","steps":["trace[310367373] 'process raft request' (duration: 148.744876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:49:14.818466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.693666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T17:49:14.818555Z","caller":"traceutil/trace.go:171","msg":"trace[2064650053] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1902; }","duration":"166.848199ms","start":"2026-05-25T17:49:14.651688Z","end":"2026-05-25T17:49:14.818537Z","steps":["trace[2064650053] 'count revisions from in-memory index tree' (duration: 166.616603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:49:14.81883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.587261ms","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-25T17:49:14.818888Z","caller":"traceutil/trace.go:171","msg":"trace[1825943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1902; }","duration":"298.694133ms","start":"2026-05-25T17:49:14.520181Z","end":"2026-05-25T17:49:14.818875Z","steps":["trace[1825943] 'range keys from in-memory index tree' (duration: 298.389326ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:49:26.588659Z","caller":"traceutil/trace.go:171","msg":"trace[1782214640] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"108.538338ms","start":"2026-05-25T17:49:26.480084Z","end":"2026-05-25T17:49:26.588623Z","steps":["trace[1782214640] 'process raft request' (duration: 59.559954ms)","trace[1782214640] 'compare' (duration: 48.84349ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:49:31.064725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.454635ms","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-25T17:49:31.064882Z","caller":"traceutil/trace.go:171","msg":"trace[727374472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2035; }","duration":"165.674721ms","start":"2026-05-25T17:49:30.899189Z","end":"2026-05-25T17:49:31.064864Z","steps":["trace[727374472] 'range keys from in-memory index tree' (duration: 165.245959ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:49:52.813456Z","caller":"traceutil/trace.go:171","msg":"trace[2033474015] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"117.304799ms","start":"2026-05-25T17:49:52.696125Z","end":"2026-05-25T17:49:52.81343Z","steps":["trace[2033474015] 'process raft request' (duration: 66.364621ms)","trace[2033474015] 'compare' (duration: 50.599179ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:49:53.318042Z","caller":"traceutil/trace.go:171","msg":"trace[12797129] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"129.075107ms","start":"2026-05-25T17:49:53.188945Z","end":"2026-05-25T17:49:53.31802Z","steps":["trace[12797129] 'process raft request' (duration: 128.950114ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:49:53.399763Z","caller":"traceutil/trace.go:171","msg":"trace[316297343] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"147.604661ms","start":"2026-05-25T17:49:53.252109Z","end":"2026-05-25T17:49:53.399714Z","steps":["trace[316297343] 'process raft request' (duration: 134.423047ms)","trace[316297343] 'compare' (duration: 12.950189ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:49:53.6569Z","caller":"traceutil/trace.go:171","msg":"trace[1128842779] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"157.331975ms","start":"2026-05-25T17:49:53.499535Z","end":"2026-05-25T17:49:53.656867Z","steps":["trace[1128842779] 'process raft request' (duration: 132.57673ms)","trace[1128842779] 'compare' (duration: 24.396796ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:49:53.868503Z","caller":"traceutil/trace.go:171","msg":"trace[2091515499] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"207.438252ms","start":"2026-05-25T17:49:53.661035Z","end":"2026-05-25T17:49:53.868473Z","steps":["trace[2091515499] 'process raft request' (duration: 169.756349ms)","trace[2091515499] 'compare' (duration: 37.466738ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:49:54.136417Z","caller":"traceutil/trace.go:171","msg":"trace[463347826] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"474.751652ms","start":"2026-05-25T17:49:53.66162Z","end":"2026-05-25T17:49:54.136371Z","steps":["trace[463347826] 'process raft request' (duration: 394.718711ms)","trace[463347826] 'compare' (duration: 79.759423ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:49:54.136651Z","caller":"traceutil/trace.go:171","msg":"trace[1049883726] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"441.511223ms","start":"2026-05-25T17:49:53.695113Z","end":"2026-05-25T17:49:54.136625Z","steps":["trace[1049883726] 'process raft request' (duration: 441.172965ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:49:54.136417Z","caller":"traceutil/trace.go:171","msg":"trace[722631223] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2244; }","duration":"446.072682ms","start":"2026-05-25T17:49:53.690321Z","end":"2026-05-25T17:49:54.136394Z","steps":["trace[722631223] 'read index received' (duration: 140.483625ms)","trace[722631223] 'applied index is now lower than readState.Index' (duration: 305.587527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:49:54.136884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:49:53.661603Z","time spent":"474.914505ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:49:54.136883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.164844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T17:49:54.137077Z","caller":"traceutil/trace.go:171","msg":"trace[2051426498] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2165; }","duration":"121.414601ms","start":"2026-05-25T17:49:54.015638Z","end":"2026-05-25T17:49:54.137053Z","steps":["trace[2051426498] 'agreement among raft nodes before linearized reading' (duration: 121.162444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:49:54.137199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:49:53.695091Z","time spent":"441.614447ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:49:54.137221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.606906ms","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-25T17:49:54.137375Z","caller":"traceutil/trace.go:171","msg":"trace[1117249079] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2165; }","duration":"447.070418ms","start":"2026-05-25T17:49:53.690281Z","end":"2026-05-25T17:49:54.137351Z","steps":["trace[1117249079] 'agreement among raft nodes before linearized reading' (duration: 446.208816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:49:54.137484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:49:53.690258Z","time spent":"447.206434ms","remote":"127.0.0.1:53578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-25T17:50:44.802599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.41821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-25T17:50:44.802697Z","caller":"traceutil/trace.go:171","msg":"trace[1043395781] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2544; }","duration":"236.590754ms","start":"2026-05-25T17:50:44.566085Z","end":"2026-05-25T17:50:44.802675Z","steps":["trace[1043395781] 'range keys from in-memory index tree' (duration: 236.242536ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:50:44.803223Z","caller":"traceutil/trace.go:171","msg":"trace[1697893977] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"146.838197ms","start":"2026-05-25T17:50:44.656369Z","end":"2026-05-25T17:50:44.803207Z","steps":["trace[1697893977] 'process raft request' (duration: 145.130842ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:51:18.548023Z","caller":"traceutil/trace.go:171","msg":"trace[1882332015] linearizableReadLoop","detail":"{readStateIndex:2833; appliedIndex:2832; }","duration":"119.404911ms","start":"2026-05-25T17:51:18.428596Z","end":"2026-05-25T17:51:18.548001Z","steps":["trace[1882332015] 'read index received' (duration: 119.320209ms)","trace[1882332015] 'applied index is now lower than readState.Index' (duration: 83.552µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:51:18.548186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.569335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:51:18.548155Z","caller":"traceutil/trace.go:171","msg":"trace[853165916] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"119.72527ms","start":"2026-05-25T17:51:18.428384Z","end":"2026-05-25T17:51:18.54811Z","steps":["trace[853165916] 'process raft request' (duration: 119.477263ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:51:18.548279Z","caller":"traceutil/trace.go:171","msg":"trace[1960153005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2731; }","duration":"119.695469ms","start":"2026-05-25T17:51:18.428564Z","end":"2026-05-25T17:51:18.54826Z","steps":["trace[1960153005] 'agreement among raft nodes before linearized reading' (duration: 119.561656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:59.463111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.361357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:54:59.463807Z","caller":"traceutil/trace.go:171","msg":"trace[1808192713] linearizableReadLoop","detail":"{readStateIndex:4846; appliedIndex:4843; }","duration":"427.158136ms","start":"2026-05-25T17:54:59.036631Z","end":"2026-05-25T17:54:59.463789Z","steps":["trace[1808192713] 'read index received' (duration: 21.979689ms)","trace[1808192713] 'applied index is now lower than readState.Index' (duration: 405.177367ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:54:59.463805Z","caller":"traceutil/trace.go:171","msg":"trace[1718278258] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"436.432299ms","start":"2026-05-25T17:54:59.027347Z","end":"2026-05-25T17:54:59.463779Z","steps":["trace[1718278258] 'process raft request' (duration: 436.103532ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:59.463925Z","caller":"traceutil/trace.go:171","msg":"trace[2057103806] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"192.986687ms","start":"2026-05-25T17:54:59.270901Z","end":"2026-05-25T17:54:59.463888Z","steps":["trace[2057103806] 'process raft request' (duration: 192.635419ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:59.464005Z","caller":"traceutil/trace.go:171","msg":"trace[301621528] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"440.972889ms","start":"2026-05-25T17:54:59.02302Z","end":"2026-05-25T17:54:59.463993Z","steps":["trace[301621528] 'process raft request' (duration: 35.627768ms)","trace[301621528] 'compare' (duration: 404.268145ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:59.464063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:59.023001Z","time spent":"441.0373ms","remote":"127.0.0.1:40796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":991,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:54:59.464231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.590346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9318"} {"level":"info","ts":"2026-05-25T17:54:59.464277Z","caller":"traceutil/trace.go:171","msg":"trace[2028447281] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4675; }","duration":"427.664288ms","start":"2026-05-25T17:54:59.036602Z","end":"2026-05-25T17:54:59.464266Z","steps":["trace[2028447281] 'agreement among raft nodes before linearized reading' (duration: 427.402692ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:59.464332Z","caller":"traceutil/trace.go:171","msg":"trace[352919582] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"438.317623ms","start":"2026-05-25T17:54:59.026004Z","end":"2026-05-25T17:54:59.464322Z","steps":["trace[352919582] 'process raft request' (duration: 437.216257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:59.464343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:59.036588Z","time spent":"427.74375ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9341,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-05-25T17:54:59.464416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:59.025998Z","time spent":"438.371116ms","remote":"127.0.0.1:40784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1148,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:54:59.463957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:59.027341Z","time spent":"436.532112ms","remote":"127.0.0.1:53370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:54:59.465042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.828744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61382"} {"level":"warn","ts":"2026-05-25T17:54:59.465481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.007015ms","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-05-25T17:54:59.465564Z","caller":"traceutil/trace.go:171","msg":"trace[126482033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4675; }","duration":"180.131628ms","start":"2026-05-25T17:54:59.285416Z","end":"2026-05-25T17:54:59.465547Z","steps":["trace[126482033] 'agreement among raft nodes before linearized reading' (duration: 179.902823ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:59.465089Z","caller":"traceutil/trace.go:171","msg":"trace[1737078953] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4675; }","duration":"383.917745ms","start":"2026-05-25T17:54:59.081161Z","end":"2026-05-25T17:54:59.465078Z","steps":["trace[1737078953] 'agreement among raft nodes before linearized reading' (duration: 383.745851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:59.4681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.924926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-05-25T17:54:59.468215Z","caller":"traceutil/trace.go:171","msg":"trace[1725367719] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4675; }","duration":"210.049828ms","start":"2026-05-25T17:54:59.258116Z","end":"2026-05-25T17:54:59.468166Z","steps":["trace[1725367719] 'agreement among raft nodes before linearized reading' (duration: 207.62924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:59.470557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:59.081145Z","time spent":"384.562261ms","remote":"127.0.0.1:40820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":6,"response size":61405,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"info","ts":"2026-05-25T17:54:59.80186Z","caller":"traceutil/trace.go:171","msg":"trace[196291447] linearizableReadLoop","detail":"{readStateIndex:4864; appliedIndex:4862; }","duration":"103.095712ms","start":"2026-05-25T17:54:59.698744Z","end":"2026-05-25T17:54:59.801839Z","steps":["trace[196291447] 'read index received' (duration: 44.368668ms)","trace[196291447] 'applied index is now lower than readState.Index' (duration: 58.726374ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:54:59.801886Z","caller":"traceutil/trace.go:171","msg":"trace[1875758955] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"112.238062ms","start":"2026-05-25T17:54:59.689612Z","end":"2026-05-25T17:54:59.80185Z","steps":["trace[1875758955] 'process raft request' (duration: 53.57756ms)","trace[1875758955] 'compare' (duration: 58.478288ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:54:59.801929Z","caller":"traceutil/trace.go:171","msg":"trace[1956509483] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"112.188721ms","start":"2026-05-25T17:54:59.689713Z","end":"2026-05-25T17:54:59.801902Z","steps":["trace[1956509483] 'process raft request' (duration: 112.074879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:59.80213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.369138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-05-25T17:54:59.802164Z","caller":"traceutil/trace.go:171","msg":"trace[761690117] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4691; }","duration":"103.45921ms","start":"2026-05-25T17:54:59.698696Z","end":"2026-05-25T17:54:59.802155Z","steps":["trace[761690117] 'agreement among raft nodes before linearized reading' (duration: 103.248585ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:55:00.003783Z","caller":"traceutil/trace.go:171","msg":"trace[1736076547] linearizableReadLoop","detail":"{readStateIndex:4867; appliedIndex:4866; }","duration":"101.298399ms","start":"2026-05-25T17:54:59.902467Z","end":"2026-05-25T17:55:00.003765Z","steps":["trace[1736076547] 'read index received' (duration: 8.090054ms)","trace[1736076547] 'applied index is now lower than readState.Index' (duration: 93.207425ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:55:00.003919Z","caller":"traceutil/trace.go:171","msg":"trace[1662339566] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"123.76371ms","start":"2026-05-25T17:54:59.880148Z","end":"2026-05-25T17:55:00.003912Z","steps":["trace[1662339566] 'process raft request' (duration: 30.490674ms)","trace[1662339566] 'compare' (duration: 93.053411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:55:00.004908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.422786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-05-25T17:55:00.004991Z","caller":"traceutil/trace.go:171","msg":"trace[2018017444] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4694; }","duration":"102.57465ms","start":"2026-05-25T17:54:59.902396Z","end":"2026-05-25T17:55:00.00497Z","steps":["trace[2018017444] 'agreement among raft nodes before linearized reading' (duration: 101.592346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:13.954612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.192678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:55:13.955123Z","caller":"traceutil/trace.go:171","msg":"trace[1719672553] linearizableReadLoop","detail":"{readStateIndex:5054; appliedIndex:5053; }","duration":"287.448096ms","start":"2026-05-25T17:55:13.667649Z","end":"2026-05-25T17:55:13.955097Z","steps":["trace[1719672553] 'read index received' (duration: 32.731µs)","trace[1719672553] 'applied index is now lower than readState.Index' (duration: 287.410825ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:55:13.955164Z","caller":"traceutil/trace.go:171","msg":"trace[629158873] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"419.937976ms","start":"2026-05-25T17:55:13.535199Z","end":"2026-05-25T17:55:13.955137Z","steps":["trace[629158873] 'process raft request' (duration: 109.131272ms)","trace[629158873] 'compare' (duration: 309.878541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:55:13.955473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:13.535186Z","time spent":"420.201521ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:55:13.955474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.803914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-25T17:55:13.955556Z","caller":"traceutil/trace.go:171","msg":"trace[499873373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4874; }","duration":"287.932087ms","start":"2026-05-25T17:55:13.667605Z","end":"2026-05-25T17:55:13.955537Z","steps":["trace[499873373] 'agreement among raft nodes before linearized reading' (duration: 287.62101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:13.955686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.365405ms","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-25T17:55:13.956441Z","caller":"traceutil/trace.go:171","msg":"trace[37945065] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4874; }","duration":"249.187386ms","start":"2026-05-25T17:55:13.707238Z","end":"2026-05-25T17:55:13.956425Z","steps":["trace[37945065] 'agreement among raft nodes before linearized reading' (duration: 248.205492ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:55.001097Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2921} {"level":"info","ts":"2026-05-25T17:56:55.099284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2921,"took":"96.742293ms","hash":184291666,"current-db-size-bytes":53501952,"current-db-size":"54 MB","current-db-size-in-use-bytes":29589504,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-25T17:56:55.099337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":184291666,"revision":2921,"compact-revision":-1} {"level":"info","ts":"2026-05-25T18:01:55.006993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5663} {"level":"info","ts":"2026-05-25T18:01:55.105855Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5663,"took":"97.194331ms","hash":8941006,"current-db-size-bytes":53501952,"current-db-size":"54 MB","current-db-size-in-use-bytes":18345984,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-25T18:01:55.105933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":8941006,"revision":5663,"compact-revision":2921}