{"level":"warn","ts":"2026-04-07T17:44:59.886508Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T17:44:59.887367Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T17:44:59.887492Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T17:44:59.887527Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-04-07T17:44:59.88755Z","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-04-07T17:44:59.88823Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-04-07T17:44:59.889541Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T17:44:59.898478Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.107161ms"} {"level":"info","ts":"2026-04-07T17:44:59.908436Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-04-07T17:44:59.908528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T17:44:59.908552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-04-07T17:44:59.908565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T17:44:59.908577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-04-07T17:44:59.908615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-04-07T17:44:59.916716Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T17:44:59.918122Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T17:44:59.921103Z","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-04-07T17:44:59.928519Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T17:44:59.929061Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T17:44:59.929163Z","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-04-07T17:44:59.930049Z","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-04-07T17:44:59.930089Z","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-04-07T17:44:59.93086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-04-07T17:44:59.931308Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-04-07T17:44:59.933137Z","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-04-07T17:44:59.933284Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-04-07T17:44:59.933312Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-04-07T17:44:59.934133Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T17:44:59.934175Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T17:45:00.708826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T17:45:00.708891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T17:45:00.708914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-04-07T17:45:00.708988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-04-07T17:45:00.709053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-04-07T17:45:00.709078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-04-07T17:45:00.709153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-04-07T17:45:00.710421Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T17:45:00.710455Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:45:00.710723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:45:00.710762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:45:00.710873Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T17:45:00.710937Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T17:45:00.711973Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:45:00.712245Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:45:00.712349Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:45:00.713079Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-04-07T17:45:00.714969Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T17:45:28.67566Z","caller":"traceutil/trace.go:171","msg":"trace[676183929] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"102.480097ms","start":"2026-04-07T17:45:28.573145Z","end":"2026-04-07T17:45:28.675625Z","steps":["trace[676183929] 'process raft request' (duration: 91.610631ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:28.678191Z","caller":"traceutil/trace.go:171","msg":"trace[140236419] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"104.776639ms","start":"2026-04-07T17:45:28.573396Z","end":"2026-04-07T17:45:28.678173Z","steps":["trace[140236419] 'process raft request' (duration: 104.643754ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:28.678393Z","caller":"traceutil/trace.go:171","msg":"trace[667985660] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"105.006776ms","start":"2026-04-07T17:45:28.573374Z","end":"2026-04-07T17:45:28.67838Z","steps":["trace[667985660] 'process raft request' (duration: 104.524239ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:28.67866Z","caller":"traceutil/trace.go:171","msg":"trace[416566373] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"105.205893ms","start":"2026-04-07T17:45:28.573442Z","end":"2026-04-07T17:45:28.678648Z","steps":["trace[416566373] 'process raft request' (duration: 104.685315ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:28.679261Z","caller":"traceutil/trace.go:171","msg":"trace[1746045715] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"101.860715ms","start":"2026-04-07T17:45:28.577388Z","end":"2026-04-07T17:45:28.679249Z","steps":["trace[1746045715] 'process raft request' (duration: 101.16867ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:28.679386Z","caller":"traceutil/trace.go:171","msg":"trace[1440508327] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"102.151484ms","start":"2026-04-07T17:45:28.577225Z","end":"2026-04-07T17:45:28.679377Z","steps":["trace[1440508327] 'process raft request' (duration: 101.146539ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:28.67947Z","caller":"traceutil/trace.go:171","msg":"trace[1126006894] transaction","detail":"{read_only:false; number_of_response:1; response_revision:479; }","duration":"102.068561ms","start":"2026-04-07T17:45:28.577394Z","end":"2026-04-07T17:45:28.679462Z","steps":["trace[1126006894] 'process raft request' (duration: 101.098198ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:28.679561Z","caller":"traceutil/trace.go:171","msg":"trace[1308931941] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"101.760871ms","start":"2026-04-07T17:45:28.577761Z","end":"2026-04-07T17:45:28.679521Z","steps":["trace[1308931941] 'process raft request' (duration: 101.019794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:45:34.536849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.376759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T17:45:34.537007Z","caller":"traceutil/trace.go:171","msg":"trace[1385407055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"157.578657ms","start":"2026-04-07T17:45:34.379403Z","end":"2026-04-07T17:45:34.536981Z","steps":["trace[1385407055] 'range keys from in-memory index tree' (duration: 157.220233ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:34.678701Z","caller":"traceutil/trace.go:171","msg":"trace[2043578475] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"182.550122ms","start":"2026-04-07T17:45:34.49612Z","end":"2026-04-07T17:45:34.67867Z","steps":["trace[2043578475] 'read index received' (duration: 181.65536ms)","trace[2043578475] 'applied index is now lower than readState.Index' (duration: 893.522µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:45:34.678803Z","caller":"traceutil/trace.go:171","msg":"trace[2117368020] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"285.523818ms","start":"2026-04-07T17:45:34.393256Z","end":"2026-04-07T17:45:34.67878Z","steps":["trace[2117368020] 'process raft request' (duration: 284.613914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:45:34.67903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.873214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cc77h\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T17:45:34.679133Z","caller":"traceutil/trace.go:171","msg":"trace[1352460885] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cc77h; range_end:; response_count:1; response_revision:565; }","duration":"183.02839ms","start":"2026-04-07T17:45:34.496079Z","end":"2026-04-07T17:45:34.679107Z","steps":["trace[1352460885] 'agreement among raft nodes before linearized reading' (duration: 182.735669ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:34.695961Z","caller":"traceutil/trace.go:171","msg":"trace[905102550] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"153.619433ms","start":"2026-04-07T17:45:34.542322Z","end":"2026-04-07T17:45:34.695942Z","steps":["trace[905102550] 'process raft request' (duration: 153.52094ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:34.915073Z","caller":"traceutil/trace.go:171","msg":"trace[1940550822] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"216.154452ms","start":"2026-04-07T17:45:34.698897Z","end":"2026-04-07T17:45:34.915051Z","steps":["trace[1940550822] 'process raft request' (duration: 216.013226ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:34.93291Z","caller":"traceutil/trace.go:171","msg":"trace[972233962] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"194.994973ms","start":"2026-04-07T17:45:34.737889Z","end":"2026-04-07T17:45:34.932884Z","steps":["trace[972233962] 'process raft request' (duration: 194.688112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:45:35.440216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.055615ms","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-04-07T17:45:35.440298Z","caller":"traceutil/trace.go:171","msg":"trace[496062643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"416.217203ms","start":"2026-04-07T17:45:35.024061Z","end":"2026-04-07T17:45:35.440278Z","steps":["trace[496062643] 'range keys from in-memory index tree' (duration: 415.884851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:45:35.440343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:45:35.024027Z","time spent":"416.306426ms","remote":"127.0.0.1:38938","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-04-07T17:45:35.440331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.734029ms","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-04-07T17:45:35.440442Z","caller":"traceutil/trace.go:171","msg":"trace[1026462284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"414.887983ms","start":"2026-04-07T17:45:35.025535Z","end":"2026-04-07T17:45:35.440423Z","steps":["trace[1026462284] 'range keys from in-memory index tree' (duration: 414.444868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:45:35.440495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:45:35.025525Z","time spent":"414.960066ms","remote":"127.0.0.1:38938","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":"warn","ts":"2026-04-07T17:45:35.440812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.039355ms","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-04-07T17:45:35.440897Z","caller":"traceutil/trace.go:171","msg":"trace[401761969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"176.186819ms","start":"2026-04-07T17:45:35.264688Z","end":"2026-04-07T17:45:35.440875Z","steps":["trace[401761969] 'range keys from in-memory index tree' (duration: 175.251785ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:35.468027Z","caller":"traceutil/trace.go:171","msg":"trace[1961108774] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"128.187554ms","start":"2026-04-07T17:45:35.339796Z","end":"2026-04-07T17:45:35.467984Z","steps":["trace[1961108774] 'process raft request' (duration: 126.15179ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:45:36.749138Z","caller":"traceutil/trace.go:171","msg":"trace[1851947109] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"112.837256ms","start":"2026-04-07T17:45:36.63628Z","end":"2026-04-07T17:45:36.749117Z","steps":["trace[1851947109] 'process raft request' (duration: 106.244686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:46:18.079816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.711125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:46:18.079925Z","caller":"traceutil/trace.go:171","msg":"trace[1481217510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"204.866129ms","start":"2026-04-07T17:46:17.875038Z","end":"2026-04-07T17:46:18.079904Z","steps":["trace[1481217510] 'range keys from in-memory index tree' (duration: 204.640461ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:46:18.080243Z","caller":"traceutil/trace.go:171","msg":"trace[1858679010] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"204.570499ms","start":"2026-04-07T17:46:17.875655Z","end":"2026-04-07T17:46:18.080226Z","steps":["trace[1858679010] 'process raft request' (duration: 188.918782ms)","trace[1858679010] 'compare' (duration: 15.488961ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:47:05.125171Z","caller":"traceutil/trace.go:171","msg":"trace[1244723220] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"135.154289ms","start":"2026-04-07T17:47:04.989977Z","end":"2026-04-07T17:47:05.125132Z","steps":["trace[1244723220] 'process raft request' (duration: 134.895133ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:17.406419Z","caller":"traceutil/trace.go:171","msg":"trace[633261120] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"141.22109ms","start":"2026-04-07T17:47:17.265166Z","end":"2026-04-07T17:47:17.406387Z","steps":["trace[633261120] 'process raft request' (duration: 139.021748ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:20.463386Z","caller":"traceutil/trace.go:171","msg":"trace[944413368] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"173.024486ms","start":"2026-04-07T17:47:20.290338Z","end":"2026-04-07T17:47:20.463362Z","steps":["trace[944413368] 'process raft request' (duration: 172.892093ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:20.463615Z","caller":"traceutil/trace.go:171","msg":"trace[1438398800] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1995; }","duration":"171.11175ms","start":"2026-04-07T17:47:20.292312Z","end":"2026-04-07T17:47:20.463423Z","steps":["trace[1438398800] 'read index received' (duration: 171.097609ms)","trace[1438398800] 'applied index is now lower than readState.Index' (duration: 11.801µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:20.463718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.375376ms","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-04-07T17:47:20.463787Z","caller":"traceutil/trace.go:171","msg":"trace[912267036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1920; }","duration":"171.461838ms","start":"2026-04-07T17:47:20.29231Z","end":"2026-04-07T17:47:20.463772Z","steps":["trace[912267036] 'agreement among raft nodes before linearized reading' (duration: 171.254033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:20.463821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.51231ms","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-04-07T17:47:20.463874Z","caller":"traceutil/trace.go:171","msg":"trace[1166962429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1920; }","duration":"171.5519ms","start":"2026-04-07T17:47:20.292286Z","end":"2026-04-07T17:47:20.463838Z","steps":["trace[1166962429] 'agreement among raft nodes before linearized reading' (duration: 171.1724ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:20.474695Z","caller":"traceutil/trace.go:171","msg":"trace[720382908] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"179.010501ms","start":"2026-04-07T17:47:20.295654Z","end":"2026-04-07T17:47:20.474664Z","steps":["trace[720382908] 'process raft request' (duration: 178.906038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:20.475111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.590703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-7ptls\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-04-07T17:47:20.47522Z","caller":"traceutil/trace.go:171","msg":"trace[1868638586] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-7ptls; range_end:; response_count:1; response_revision:1921; }","duration":"169.730527ms","start":"2026-04-07T17:47:20.305462Z","end":"2026-04-07T17:47:20.475193Z","steps":["trace[1868638586] 'agreement among raft nodes before linearized reading' (duration: 169.469921ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:43.734767Z","caller":"traceutil/trace.go:171","msg":"trace[959116620] linearizableReadLoop","detail":"{readStateIndex:2142; appliedIndex:2141; }","duration":"104.845172ms","start":"2026-04-07T17:47:43.629901Z","end":"2026-04-07T17:47:43.734746Z","steps":["trace[959116620] 'read index received' (duration: 104.694268ms)","trace[959116620] 'applied index is now lower than readState.Index' (duration: 149.824µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:47:43.734892Z","caller":"traceutil/trace.go:171","msg":"trace[708173188] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"112.656439ms","start":"2026-04-07T17:47:43.622221Z","end":"2026-04-07T17:47:43.734878Z","steps":["trace[708173188] 'process raft request' (duration: 112.387991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:43.735188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.264924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:43.73528Z","caller":"traceutil/trace.go:171","msg":"trace[1476078876] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2062; }","duration":"105.389397ms","start":"2026-04-07T17:47:43.62987Z","end":"2026-04-07T17:47:43.73526Z","steps":["trace[1476078876] 'agreement among raft nodes before linearized reading' (duration: 105.230153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:44.353088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.418268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:47:44.353232Z","caller":"traceutil/trace.go:171","msg":"trace[1088638680] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"178.446082ms","start":"2026-04-07T17:47:44.174758Z","end":"2026-04-07T17:47:44.353204Z","steps":["trace[1088638680] 'process raft request' (duration: 48.301584ms)","trace[1088638680] 'compare' (duration: 129.242853ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:47:44.353395Z","caller":"traceutil/trace.go:171","msg":"trace[156744954] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"141.949697ms","start":"2026-04-07T17:47:44.211292Z","end":"2026-04-07T17:47:44.353241Z","steps":["trace[156744954] 'process raft request' (duration: 141.889146ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:44.353278Z","caller":"traceutil/trace.go:171","msg":"trace[200799540] linearizableReadLoop","detail":"{readStateIndex:2144; appliedIndex:2143; }","duration":"167.721143ms","start":"2026-04-07T17:47:44.185525Z","end":"2026-04-07T17:47:44.353246Z","steps":["trace[200799540] 'read index received' (duration: 35.673622ms)","trace[200799540] 'applied index is now lower than readState.Index' (duration: 132.045191ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:44.353674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.126805ms","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-04-07T17:47:44.353726Z","caller":"traceutil/trace.go:171","msg":"trace[276688071] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2065; }","duration":"168.218087ms","start":"2026-04-07T17:47:44.185492Z","end":"2026-04-07T17:47:44.35371Z","steps":["trace[276688071] 'agreement among raft nodes before linearized reading' (duration: 167.981231ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:44.826005Z","caller":"traceutil/trace.go:171","msg":"trace[645424125] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"261.83528ms","start":"2026-04-07T17:47:44.564147Z","end":"2026-04-07T17:47:44.825982Z","steps":["trace[645424125] 'process raft request' (duration: 261.42847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:44.826278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.778788ms","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-04-07T17:47:44.826403Z","caller":"traceutil/trace.go:171","msg":"trace[585988931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2066; }","duration":"256.918353ms","start":"2026-04-07T17:47:44.569459Z","end":"2026-04-07T17:47:44.826378Z","steps":["trace[585988931] 'agreement among raft nodes before linearized reading' (duration: 256.620625ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:44.825889Z","caller":"traceutil/trace.go:171","msg":"trace[1120832417] linearizableReadLoop","detail":"{readStateIndex:2146; appliedIndex:2145; }","duration":"256.363958ms","start":"2026-04-07T17:47:44.569482Z","end":"2026-04-07T17:47:44.825846Z","steps":["trace[1120832417] 'read index received' (duration: 256.001127ms)","trace[1120832417] 'applied index is now lower than readState.Index' (duration: 361.291µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:45.130863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.680804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:47:45.131126Z","caller":"traceutil/trace.go:171","msg":"trace[1026663348] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2146; }","duration":"304.557354ms","start":"2026-04-07T17:47:44.82654Z","end":"2026-04-07T17:47:45.131097Z","steps":["trace[1026663348] 'read index received' (duration: 166.657564ms)","trace[1026663348] 'applied index is now lower than readState.Index' (duration: 137.89609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:47:45.131534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.256283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-07T17:47:45.131573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.356966ms","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-04-07T17:47:45.131704Z","caller":"traceutil/trace.go:171","msg":"trace[66071005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2068; }","duration":"533.571852ms","start":"2026-04-07T17:47:44.598109Z","end":"2026-04-07T17:47:45.131681Z","steps":["trace[66071005] 'agreement among raft nodes before linearized reading' (duration: 533.118539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:45.131787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:44.598085Z","time spent":"533.692285ms","remote":"127.0.0.1:38938","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-04-07T17:47:45.131771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.434796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:47:45.131808Z","caller":"traceutil/trace.go:171","msg":"trace[516824740] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"529.290282ms","start":"2026-04-07T17:47:44.601782Z","end":"2026-04-07T17:47:45.131072Z","steps":["trace[516824740] 'process raft request' (duration: 391.31177ms)","trace[516824740] 'compare' (duration: 137.494429ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:47:45.131863Z","caller":"traceutil/trace.go:171","msg":"trace[753432032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2068; }","duration":"256.56187ms","start":"2026-04-07T17:47:44.875279Z","end":"2026-04-07T17:47:45.131841Z","steps":["trace[753432032] 'agreement among raft nodes before linearized reading' (duration: 256.420486ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:45.131837Z","caller":"traceutil/trace.go:171","msg":"trace[1148856873] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"301.063047ms","start":"2026-04-07T17:47:44.830714Z","end":"2026-04-07T17:47:45.131777Z","steps":["trace[1148856873] 'process raft request' (duration: 300.254685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:45.131979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:44.601769Z","time spent":"530.114427ms","remote":"127.0.0.1:38938","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-04-07T17:47:45.132088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:44.830692Z","time spent":"301.314055ms","remote":"127.0.0.1:38938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:47:45.131592Z","caller":"traceutil/trace.go:171","msg":"trace[1639401855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2068; }","duration":"533.350345ms","start":"2026-04-07T17:47:44.598226Z","end":"2026-04-07T17:47:45.131576Z","steps":["trace[1639401855] 'agreement among raft nodes before linearized reading' (duration: 532.939994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:47:45.13216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:47:44.598206Z","time spent":"533.941023ms","remote":"127.0.0.1:38938","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":"info","ts":"2026-04-07T17:47:45.325707Z","caller":"traceutil/trace.go:171","msg":"trace[1384630705] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"187.785505ms","start":"2026-04-07T17:47:45.137896Z","end":"2026-04-07T17:47:45.325682Z","steps":["trace[1384630705] 'process raft request' (duration: 179.377199ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:47:45.361414Z","caller":"traceutil/trace.go:171","msg":"trace[1329132335] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"145.333259ms","start":"2026-04-07T17:47:45.21606Z","end":"2026-04-07T17:47:45.361393Z","steps":["trace[1329132335] 'process raft request' (duration: 145.057751ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:48:34.698222Z","caller":"traceutil/trace.go:171","msg":"trace[406014794] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"171.838897ms","start":"2026-04-07T17:48:34.52636Z","end":"2026-04-07T17:48:34.698198Z","steps":["trace[406014794] 'process raft request' (duration: 171.718253ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:48:34.698721Z","caller":"traceutil/trace.go:171","msg":"trace[1951574518] linearizableReadLoop","detail":"{readStateIndex:2532; appliedIndex:2532; }","duration":"123.405139ms","start":"2026-04-07T17:48:34.575304Z","end":"2026-04-07T17:48:34.698709Z","steps":["trace[1951574518] 'read index received' (duration: 123.401409ms)","trace[1951574518] 'applied index is now lower than readState.Index' (duration: 3.06µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:48:34.698844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.526203ms","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-04-07T17:48:34.698876Z","caller":"traceutil/trace.go:171","msg":"trace[484135906] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2440; }","duration":"123.590045ms","start":"2026-04-07T17:48:34.575275Z","end":"2026-04-07T17:48:34.698865Z","steps":["trace[484135906] 'agreement among raft nodes before linearized reading' (duration: 123.475501ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:35.664757Z","caller":"traceutil/trace.go:171","msg":"trace[1842907040] linearizableReadLoop","detail":"{readStateIndex:4442; appliedIndex:4441; }","duration":"119.638752ms","start":"2026-04-07T17:52:35.545096Z","end":"2026-04-07T17:52:35.664735Z","steps":["trace[1842907040] 'read index received' (duration: 119.433837ms)","trace[1842907040] 'applied index is now lower than readState.Index' (duration: 204.075µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:35.665023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.905718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-07T17:52:35.665083Z","caller":"traceutil/trace.go:171","msg":"trace[383353829] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4275; }","duration":"120.010391ms","start":"2026-04-07T17:52:35.545055Z","end":"2026-04-07T17:52:35.665066Z","steps":["trace[383353829] 'agreement among raft nodes before linearized reading' (duration: 119.793436ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:35.665078Z","caller":"traceutil/trace.go:171","msg":"trace[1443755197] transaction","detail":"{read_only:false; response_revision:4275; number_of_response:1; }","duration":"129.244532ms","start":"2026-04-07T17:52:35.535812Z","end":"2026-04-07T17:52:35.665056Z","steps":["trace[1443755197] 'process raft request' (duration: 128.776341ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:50.892366Z","caller":"traceutil/trace.go:171","msg":"trace[521713838] transaction","detail":"{read_only:false; response_revision:4557; number_of_response:1; }","duration":"162.16259ms","start":"2026-04-07T17:52:50.730186Z","end":"2026-04-07T17:52:50.892348Z","steps":["trace[521713838] 'process raft request' (duration: 162.074168ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:50.892681Z","caller":"traceutil/trace.go:171","msg":"trace[1122372101] linearizableReadLoop","detail":"{readStateIndex:4731; appliedIndex:4731; }","duration":"153.390199ms","start":"2026-04-07T17:52:50.739285Z","end":"2026-04-07T17:52:50.892676Z","steps":["trace[1122372101] 'read index received' (duration: 153.387679ms)","trace[1122372101] 'applied index is now lower than readState.Index' (duration: 2.12µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:50.892886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.595633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T17:52:50.892904Z","caller":"traceutil/trace.go:171","msg":"trace[1890159003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4557; }","duration":"153.623934ms","start":"2026-04-07T17:52:50.739274Z","end":"2026-04-07T17:52:50.892898Z","steps":["trace[1890159003] 'agreement among raft nodes before linearized reading' (duration: 153.571883ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:59.351867Z","caller":"traceutil/trace.go:171","msg":"trace[1595329384] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"112.0827ms","start":"2026-04-07T17:52:59.239746Z","end":"2026-04-07T17:52:59.351829Z","steps":["trace[1595329384] 'process raft request' (duration: 111.802633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:26.309029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.364876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:53:26.309409Z","caller":"traceutil/trace.go:171","msg":"trace[2142685451] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"366.20674ms","start":"2026-04-07T17:53:25.943173Z","end":"2026-04-07T17:53:26.30938Z","steps":["trace[2142685451] 'process raft request' (duration: 140.376052ms)","trace[2142685451] 'compare' (duration: 224.483075ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:26.309492Z","caller":"traceutil/trace.go:171","msg":"trace[1800311963] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5192; }","duration":"158.863623ms","start":"2026-04-07T17:53:26.150609Z","end":"2026-04-07T17:53:26.309472Z","steps":["trace[1800311963] 'read index received' (duration: 19.34335ms)","trace[1800311963] 'applied index is now lower than readState.Index' (duration: 139.518903ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:53:26.309582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:25.943161Z","time spent":"366.338283ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7773,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T17:53:26.309594Z","caller":"traceutil/trace.go:171","msg":"trace[260557628] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"315.692254ms","start":"2026-04-07T17:53:25.993893Z","end":"2026-04-07T17:53:26.309585Z","steps":["trace[260557628] 'process raft request' (duration: 315.459338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:26.309663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:53:25.993879Z","time spent":"315.742855ms","remote":"127.0.0.1:38938","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-04-07T17:53:26.309753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.12065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:26.309848Z","caller":"traceutil/trace.go:171","msg":"trace[1640700544] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5009; }","duration":"159.236833ms","start":"2026-04-07T17:53:26.150581Z","end":"2026-04-07T17:53:26.309818Z","steps":["trace[1640700544] 'agreement among raft nodes before linearized reading' (duration: 159.11468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:53:26.309931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.262937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:53:26.310015Z","caller":"traceutil/trace.go:171","msg":"trace[1625478202] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5009; }","duration":"124.429351ms","start":"2026-04-07T17:53:26.185568Z","end":"2026-04-07T17:53:26.309997Z","steps":["trace[1625478202] 'agreement among raft nodes before linearized reading' (duration: 124.087373ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:26.543396Z","caller":"traceutil/trace.go:171","msg":"trace[684639929] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"222.692519ms","start":"2026-04-07T17:53:26.320677Z","end":"2026-04-07T17:53:26.543369Z","steps":["trace[684639929] 'process raft request' (duration: 134.574208ms)","trace[684639929] 'compare' (duration: 87.523286ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T17:53:26.543482Z","caller":"traceutil/trace.go:171","msg":"trace[986816273] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"175.161446ms","start":"2026-04-07T17:53:26.368296Z","end":"2026-04-07T17:53:26.543458Z","steps":["trace[986816273] 'process raft request' (duration: 174.697604ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:55:00.741924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2903} {"level":"info","ts":"2026-04-07T17:55:00.814756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2903,"took":"71.279898ms","hash":4099275406,"current-db-size-bytes":54366208,"current-db-size":"54 MB","current-db-size-in-use-bytes":29335552,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T17:55:00.814827Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4099275406,"revision":2903,"compact-revision":-1} {"level":"info","ts":"2026-04-07T18:00:00.746553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5637} {"level":"info","ts":"2026-04-07T18:00:00.80874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5637,"took":"60.997438ms","hash":1395682958,"current-db-size-bytes":54366208,"current-db-size":"54 MB","current-db-size-in-use-bytes":18345984,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T18:00:00.808796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1395682958,"revision":5637,"compact-revision":2903}