{"level":"warn","ts":"2026-04-27T11:59:37.709954Z","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-27T11:59:37.710055Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.186:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.186:2380","--initial-cluster=instance=https://199.19.213.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.186: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-27T11:59:37.710155Z","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-27T11:59:37.710179Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-04-27T11:59:37.71021Z","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-27T11:59:37.711269Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"]} {"level":"info","ts":"2026-04-27T11:59:37.711385Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.186: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-27T11:59:37.716914Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.285215ms"} {"level":"info","ts":"2026-04-27T11:59:37.727589Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"22478416d949d47d","cluster-id":"568bb29601528c07"} {"level":"info","ts":"2026-04-27T11:59:37.727661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T11:59:37.727689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 0"} {"level":"info","ts":"2026-04-27T11:59:37.727706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 22478416d949d47d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T11:59:37.727715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 1"} {"level":"info","ts":"2026-04-27T11:59:37.727787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"warn","ts":"2026-04-27T11:59:37.733586Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T11:59:37.735335Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T11:59:37.737554Z","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-27T11:59:37.742256Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"22478416d949d47d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T11:59:37.742816Z","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-27T11:59:37.742906Z","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-27T11:59:37.742918Z","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-27T11:59:37.742859Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"22478416d949d47d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T11:59:37.7434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"info","ts":"2026-04-27T11:59:37.744108Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","added-peer-id":"22478416d949d47d","added-peer-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-04-27T11:59:37.747699Z","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-27T11:59:37.748054Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-04-27T11:59:37.748113Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-04-27T11:59:37.748912Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"22478416d949d47d","initial-advertise-peer-urls":["https://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T11:59:37.748954Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T11:59:37.928504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T11:59:37.928591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T11:59:37.928614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgPreVoteResp from 22478416d949d47d at term 1"} {"level":"info","ts":"2026-04-27T11:59:37.928634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became candidate at term 2"} {"level":"info","ts":"2026-04-27T11:59:37.92865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgVoteResp from 22478416d949d47d at term 2"} {"level":"info","ts":"2026-04-27T11:59:37.928666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became leader at term 2"} {"level":"info","ts":"2026-04-27T11:59:37.928684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 22478416d949d47d elected leader 22478416d949d47d at term 2"} {"level":"info","ts":"2026-04-27T11:59:37.937957Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"22478416d949d47d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.186:2379]}","request-path":"/0/members/22478416d949d47d/attributes","cluster-id":"568bb29601528c07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T11:59:37.938001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T11:59:37.938129Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T11:59:37.938208Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:59:37.938418Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T11:59:37.93844Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T11:59:37.939403Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:59:37.939505Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:59:37.939531Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:59:37.940199Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.186:2379"} {"level":"info","ts":"2026-04-27T11:59:37.941708Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T12:00:12.997075Z","caller":"traceutil/trace.go:171","msg":"trace[121170142] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"191.753054ms","start":"2026-04-27T12:00:12.8053Z","end":"2026-04-27T12:00:12.997053Z","steps":["trace[121170142] 'read index received' (duration: 191.574252ms)","trace[121170142] 'applied index is now lower than readState.Index' (duration: 178.022µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:00:12.997319Z","caller":"traceutil/trace.go:171","msg":"trace[1649637057] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"241.835782ms","start":"2026-04-27T12:00:12.755474Z","end":"2026-04-27T12:00:12.99731Z","steps":["trace[1649637057] 'process raft request' (duration: 241.452616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:12.997508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.19197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-82dkl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-27T12:00:12.997548Z","caller":"traceutil/trace.go:171","msg":"trace[1217097378] range","detail":"{range_begin:/registry/pods/kube-system/cilium-82dkl; range_end:; response_count:1; response_revision:576; }","duration":"192.272031ms","start":"2026-04-27T12:00:12.805269Z","end":"2026-04-27T12:00:12.997541Z","steps":["trace[1217097378] 'agreement among raft nodes before linearized reading' (duration: 192.169529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:13.5858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.183339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:00:13.585928Z","caller":"traceutil/trace.go:171","msg":"trace[926303388] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"572.663929ms","start":"2026-04-27T12:00:13.013245Z","end":"2026-04-27T12:00:13.585909Z","steps":["trace[926303388] 'process raft request' (duration: 280.62457ms)","trace[926303388] 'compare' (duration: 291.077457ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:00:13.585999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:13.013221Z","time spent":"572.7451ms","remote":"127.0.0.1:39852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:00:13.603539Z","caller":"traceutil/trace.go:171","msg":"trace[916576920] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"252.629314ms","start":"2026-04-27T12:00:13.350879Z","end":"2026-04-27T12:00:13.603508Z","steps":["trace[916576920] 'process raft request' (duration: 252.517062ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:13.603553Z","caller":"traceutil/trace.go:171","msg":"trace[709504302] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:600; }","duration":"212.072653ms","start":"2026-04-27T12:00:13.39144Z","end":"2026-04-27T12:00:13.603512Z","steps":["trace[709504302] 'read index received' (duration: 212.052963ms)","trace[709504302] 'applied index is now lower than readState.Index' (duration: 16.18µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:00:13.603906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.457688ms","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-27T12:00:13.603949Z","caller":"traceutil/trace.go:171","msg":"trace[1790152048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"212.548649ms","start":"2026-04-27T12:00:13.391386Z","end":"2026-04-27T12:00:13.603934Z","steps":["trace[1790152048] 'agreement among raft nodes before linearized reading' (duration: 212.309696ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:13.621578Z","caller":"traceutil/trace.go:171","msg":"trace[225107291] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"224.169841ms","start":"2026-04-27T12:00:13.397374Z","end":"2026-04-27T12:00:13.621544Z","steps":["trace[225107291] 'process raft request' (duration: 220.089348ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:13.621883Z","caller":"traceutil/trace.go:171","msg":"trace[743356363] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"114.100321ms","start":"2026-04-27T12:00:13.507749Z","end":"2026-04-27T12:00:13.62185Z","steps":["trace[743356363] 'process raft request' (duration: 113.704576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:08.232228Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311567819694344016,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T12:01:08.239364Z","caller":"traceutil/trace.go:171","msg":"trace[702194040] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"508.117058ms","start":"2026-04-27T12:01:07.731222Z","end":"2026-04-27T12:01:08.239339Z","steps":["trace[702194040] 'process raft request' (duration: 507.486709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:08.239522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:07.731204Z","time spent":"508.227654ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:01:08.249652Z","caller":"traceutil/trace.go:171","msg":"trace[572846364] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"513.444995ms","start":"2026-04-27T12:01:07.736179Z","end":"2026-04-27T12:01:08.249623Z","steps":["trace[572846364] 'process raft request' (duration: 513.181763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:08.249896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:07.736166Z","time spent":"513.638064ms","remote":"127.0.0.1:39852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:01:08.250089Z","caller":"traceutil/trace.go:171","msg":"trace[477464105] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1004; }","duration":"518.525966ms","start":"2026-04-27T12:01:07.731418Z","end":"2026-04-27T12:01:08.249944Z","steps":["trace[477464105] 'read index received' (duration: 507.214566ms)","trace[477464105] 'applied index is now lower than readState.Index' (duration: 11.30917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:01:08.250132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.693185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:01:08.250203Z","caller":"traceutil/trace.go:171","msg":"trace[923954176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"518.773638ms","start":"2026-04-27T12:01:07.731406Z","end":"2026-04-27T12:01:08.250179Z","steps":["trace[923954176] 'agreement among raft nodes before linearized reading' (duration: 518.669023ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:08.250202Z","caller":"traceutil/trace.go:171","msg":"trace[674768321] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"117.856665ms","start":"2026-04-27T12:01:08.132172Z","end":"2026-04-27T12:01:08.250029Z","steps":["trace[674768321] 'process raft request' (duration: 117.375362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:08.25026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:07.731399Z","time spent":"518.849932ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T12:01:08.250369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.129184ms","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-27T12:01:08.250398Z","caller":"traceutil/trace.go:171","msg":"trace[1246966654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"356.216677ms","start":"2026-04-27T12:01:07.894173Z","end":"2026-04-27T12:01:08.25039Z","steps":["trace[1246966654] 'agreement among raft nodes before linearized reading' (duration: 356.04623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:08.250379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.973293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:01:08.250456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:07.894159Z","time spent":"356.287971ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T12:01:08.250453Z","caller":"traceutil/trace.go:171","msg":"trace[684073770] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:972; }","duration":"265.067629ms","start":"2026-04-27T12:01:07.985367Z","end":"2026-04-27T12:01:08.250434Z","steps":["trace[684073770] 'agreement among raft nodes before linearized reading' (duration: 264.932292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:08.250643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.012573ms","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-27T12:01:08.250679Z","caller":"traceutil/trace.go:171","msg":"trace[1888944153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"354.080306ms","start":"2026-04-27T12:01:07.89659Z","end":"2026-04-27T12:01:08.25067Z","steps":["trace[1888944153] 'agreement among raft nodes before linearized reading' (duration: 353.93333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:08.250723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:07.896581Z","time spent":"354.135448ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T12:01:08.250809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.972546ms","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-27T12:01:08.250854Z","caller":"traceutil/trace.go:171","msg":"trace[401954047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"110.03816ms","start":"2026-04-27T12:01:08.140806Z","end":"2026-04-27T12:01:08.250844Z","steps":["trace[401954047] 'agreement among raft nodes before linearized reading' (duration: 109.83353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:08.250847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.253901ms","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-27T12:01:08.250899Z","caller":"traceutil/trace.go:171","msg":"trace[853985296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:972; }","duration":"355.311644ms","start":"2026-04-27T12:01:07.895572Z","end":"2026-04-27T12:01:08.250883Z","steps":["trace[853985296] 'agreement among raft nodes before linearized reading' (duration: 355.107855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:08.250986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:07.895567Z","time spent":"355.408429ms","remote":"127.0.0.1:39904","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-04-27T12:02:13.536094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.995552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:02:13.536211Z","caller":"traceutil/trace.go:171","msg":"trace[693606819] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1885; }","duration":"347.163205ms","start":"2026-04-27T12:02:13.189024Z","end":"2026-04-27T12:02:13.536187Z","steps":["trace[693606819] 'count revisions from in-memory index tree' (duration: 346.858728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:13.536289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:13.189004Z","time spent":"347.247358ms","remote":"127.0.0.1:39890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-04-27T12:02:13.53708Z","caller":"traceutil/trace.go:171","msg":"trace[1768880471] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"301.234544ms","start":"2026-04-27T12:02:13.235812Z","end":"2026-04-27T12:02:13.537047Z","steps":["trace[1768880471] 'process raft request' (duration: 238.00611ms)","trace[1768880471] 'compare' (duration: 63.091201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:13.53736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:13.235787Z","time spent":"301.381729ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:02:27.202199Z","caller":"traceutil/trace.go:171","msg":"trace[442212862] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"140.552745ms","start":"2026-04-27T12:02:27.061616Z","end":"2026-04-27T12:02:27.202169Z","steps":["trace[442212862] 'process raft request' (duration: 138.860401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:27.527689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.960138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:02:27.527855Z","caller":"traceutil/trace.go:171","msg":"trace[1008478244] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2011; }","duration":"143.210224ms","start":"2026-04-27T12:02:27.384616Z","end":"2026-04-27T12:02:27.527826Z","steps":["trace[1008478244] 'count revisions from in-memory index tree' (duration: 142.876926ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:43.3981Z","caller":"traceutil/trace.go:171","msg":"trace[1382047933] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"134.909563ms","start":"2026-04-27T12:02:43.263173Z","end":"2026-04-27T12:02:43.398082Z","steps":["trace[1382047933] 'process raft request' (duration: 134.7995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:45.267854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.614758ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:02:45.268605Z","caller":"traceutil/trace.go:171","msg":"trace[836624644] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2114; }","duration":"257.376636ms","start":"2026-04-27T12:02:45.011209Z","end":"2026-04-27T12:02:45.268586Z","steps":["trace[836624644] 'range keys from in-memory index tree' (duration: 256.597278ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:45.268749Z","caller":"traceutil/trace.go:171","msg":"trace[1036947928] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"239.488673ms","start":"2026-04-27T12:02:45.029231Z","end":"2026-04-27T12:02:45.268719Z","steps":["trace[1036947928] 'process raft request' (duration: 239.37517ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:02:45.268847Z","caller":"traceutil/trace.go:171","msg":"trace[1209121387] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2197; }","duration":"305.422468ms","start":"2026-04-27T12:02:44.963383Z","end":"2026-04-27T12:02:45.268805Z","steps":["trace[1209121387] 'read index received' (duration: 267.009288ms)","trace[1209121387] 'applied index is now lower than readState.Index' (duration: 38.4097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:45.269044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.661923ms","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-27T12:02:45.269093Z","caller":"traceutil/trace.go:171","msg":"trace[1587589108] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2116; }","duration":"305.739806ms","start":"2026-04-27T12:02:44.963333Z","end":"2026-04-27T12:02:45.269073Z","steps":["trace[1587589108] 'agreement among raft nodes before linearized reading' (duration: 305.593283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:02:45.269127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:44.963312Z","time spent":"305.806547ms","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-27T12:02:45.269196Z","caller":"traceutil/trace.go:171","msg":"trace[380626906] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"360.451677ms","start":"2026-04-27T12:02:44.908719Z","end":"2026-04-27T12:02:45.269171Z","steps":["trace[380626906] 'process raft request' (duration: 321.516816ms)","trace[380626906] 'compare' (duration: 37.302033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:02:45.269344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:02:44.908698Z","time spent":"360.550069ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:03:59.392235Z","caller":"traceutil/trace.go:171","msg":"trace[1550008803] linearizableReadLoop","detail":"{readStateIndex:2713; appliedIndex:2711; }","duration":"157.206628ms","start":"2026-04-27T12:03:59.235006Z","end":"2026-04-27T12:03:59.392212Z","steps":["trace[1550008803] 'read index received' (duration: 156.955083ms)","trace[1550008803] 'applied index is now lower than readState.Index' (duration: 250.664µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:03:59.392269Z","caller":"traceutil/trace.go:171","msg":"trace[828367669] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"195.058056ms","start":"2026-04-27T12:03:59.197192Z","end":"2026-04-27T12:03:59.39225Z","steps":["trace[828367669] 'process raft request' (duration: 194.977835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:03:59.392447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.431612ms","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-27T12:03:59.392504Z","caller":"traceutil/trace.go:171","msg":"trace[689154151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2612; }","duration":"157.530593ms","start":"2026-04-27T12:03:59.234961Z","end":"2026-04-27T12:03:59.392492Z","steps":["trace[689154151] 'agreement among raft nodes before linearized reading' (duration: 157.343819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:03:59.392667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.042598ms","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-27T12:03:59.392707Z","caller":"traceutil/trace.go:171","msg":"trace[671116260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2612; }","duration":"152.091159ms","start":"2026-04-27T12:03:59.240605Z","end":"2026-04-27T12:03:59.392696Z","steps":["trace[671116260] 'agreement among raft nodes before linearized reading' (duration: 151.972096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:03:59.392824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.678552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T12:03:59.392845Z","caller":"traceutil/trace.go:171","msg":"trace[1828176924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2612; }","duration":"150.731793ms","start":"2026-04-27T12:03:59.242107Z","end":"2026-04-27T12:03:59.392839Z","steps":["trace[1828176924] 'agreement among raft nodes before linearized reading' (duration: 150.670321ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:03:59.392269Z","caller":"traceutil/trace.go:171","msg":"trace[1336202345] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"195.056396ms","start":"2026-04-27T12:03:59.197175Z","end":"2026-04-27T12:03:59.392232Z","steps":["trace[1336202345] 'process raft request' (duration: 194.834291ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:04:33.113993Z","caller":"traceutil/trace.go:171","msg":"trace[1309064000] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"152.891288ms","start":"2026-04-27T12:04:32.961054Z","end":"2026-04-27T12:04:33.113945Z","steps":["trace[1309064000] 'process raft request' (duration: 152.547242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:04:33.287884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.024959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"info","ts":"2026-04-27T12:04:33.288053Z","caller":"traceutil/trace.go:171","msg":"trace[1973749021] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2798; }","duration":"166.288023ms","start":"2026-04-27T12:04:33.121745Z","end":"2026-04-27T12:04:33.288033Z","steps":["trace[1973749021] 'range keys from in-memory index tree' (duration: 165.727303ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:20.918668Z","caller":"traceutil/trace.go:171","msg":"trace[2093663471] transaction","detail":"{read_only:false; response_revision:4531; number_of_response:1; }","duration":"111.302389ms","start":"2026-04-27T12:08:20.807351Z","end":"2026-04-27T12:08:20.918653Z","steps":["trace[2093663471] 'process raft request' (duration: 111.245588ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:20.918793Z","caller":"traceutil/trace.go:171","msg":"trace[1054990810] linearizableReadLoop","detail":"{readStateIndex:4707; appliedIndex:4706; }","duration":"142.852697ms","start":"2026-04-27T12:08:20.775926Z","end":"2026-04-27T12:08:20.918778Z","steps":["trace[1054990810] 'read index received' (duration: 82.606833ms)","trace[1054990810] 'applied index is now lower than readState.Index' (duration: 60.244084ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:20.918869Z","caller":"traceutil/trace.go:171","msg":"trace[146737914] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"149.42513ms","start":"2026-04-27T12:08:20.769363Z","end":"2026-04-27T12:08:20.918788Z","steps":["trace[146737914] 'process raft request' (duration: 89.158036ms)","trace[146737914] 'compare' (duration: 59.899107ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:20.919163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.184194ms","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-04-27T12:08:20.919288Z","caller":"traceutil/trace.go:171","msg":"trace[1919897830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4531; }","duration":"143.379489ms","start":"2026-04-27T12:08:20.775883Z","end":"2026-04-27T12:08:20.919262Z","steps":["trace[1919897830] 'agreement among raft nodes before linearized reading' (duration: 142.97459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:20.919256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.395964ms","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-04-27T12:08:20.91942Z","caller":"traceutil/trace.go:171","msg":"trace[1792762250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4531; }","duration":"140.567949ms","start":"2026-04-27T12:08:20.778798Z","end":"2026-04-27T12:08:20.919365Z","steps":["trace[1792762250] 'agreement among raft nodes before linearized reading' (duration: 140.267162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:26.121063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.149876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:08:26.12119Z","caller":"traceutil/trace.go:171","msg":"trace[182653729] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4879; }","duration":"139.332582ms","start":"2026-04-27T12:08:25.981839Z","end":"2026-04-27T12:08:26.121172Z","steps":["trace[182653729] 'read index received' (duration: 30.864179ms)","trace[182653729] 'applied index is now lower than readState.Index' (duration: 108.467223ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:26.121258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.418845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:08:26.121294Z","caller":"traceutil/trace.go:171","msg":"trace[1827060124] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4698; }","duration":"139.499496ms","start":"2026-04-27T12:08:25.981784Z","end":"2026-04-27T12:08:26.121283Z","steps":["trace[1827060124] 'agreement among raft nodes before linearized reading' (duration: 139.435355ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:26.121301Z","caller":"traceutil/trace.go:171","msg":"trace[1097806935] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"141.392287ms","start":"2026-04-27T12:08:25.979887Z","end":"2026-04-27T12:08:26.121279Z","steps":["trace[1097806935] 'process raft request' (duration: 32.912335ms)","trace[1097806935] 'compare' (duration: 107.997072ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:27.24917Z","caller":"traceutil/trace.go:171","msg":"trace[418658738] linearizableReadLoop","detail":"{readStateIndex:4908; appliedIndex:4907; }","duration":"157.541213ms","start":"2026-04-27T12:08:27.0916Z","end":"2026-04-27T12:08:27.249141Z","steps":["trace[418658738] 'read index received' (duration: 154.078949ms)","trace[418658738] 'applied index is now lower than readState.Index' (duration: 3.456894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:27.249348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.724578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:08:27.249382Z","caller":"traceutil/trace.go:171","msg":"trace[1346712668] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4725; }","duration":"157.800399ms","start":"2026-04-27T12:08:27.091571Z","end":"2026-04-27T12:08:27.249371Z","steps":["trace[1346712668] 'agreement among raft nodes before linearized reading' (duration: 157.673977ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:27.249751Z","caller":"traceutil/trace.go:171","msg":"trace[1583129781] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"165.852693ms","start":"2026-04-27T12:08:27.083882Z","end":"2026-04-27T12:08:27.249735Z","steps":["trace[1583129781] 'process raft request' (duration: 161.891617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:33.516602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.494249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:08:33.516887Z","caller":"traceutil/trace.go:171","msg":"trace[611340013] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"296.079719ms","start":"2026-04-27T12:08:33.220752Z","end":"2026-04-27T12:08:33.516832Z","steps":["trace[611340013] 'process raft request' (duration: 154.222913ms)","trace[611340013] 'compare' (duration: 141.365126ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:33.51694Z","caller":"traceutil/trace.go:171","msg":"trace[1549815840] linearizableReadLoop","detail":"{readStateIndex:5033; appliedIndex:5029; }","duration":"237.64319ms","start":"2026-04-27T12:08:33.27928Z","end":"2026-04-27T12:08:33.516923Z","steps":["trace[1549815840] 'read index received' (duration: 95.880466ms)","trace[1549815840] 'applied index is now lower than readState.Index' (duration: 141.761424ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:33.517158Z","caller":"traceutil/trace.go:171","msg":"trace[1153345826] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"295.005436ms","start":"2026-04-27T12:08:33.222091Z","end":"2026-04-27T12:08:33.517097Z","steps":["trace[1153345826] 'process raft request' (duration: 294.78032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:33.517412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.13263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-27T12:08:33.517464Z","caller":"traceutil/trace.go:171","msg":"trace[1851953286] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"295.496056ms","start":"2026-04-27T12:08:33.221948Z","end":"2026-04-27T12:08:33.517444Z","steps":["trace[1851953286] 'process raft request' (duration: 294.865803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:33.517499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.659144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-27T12:08:33.517556Z","caller":"traceutil/trace.go:171","msg":"trace[578389916] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4848; }","duration":"220.751117ms","start":"2026-04-27T12:08:33.296785Z","end":"2026-04-27T12:08:33.517536Z","steps":["trace[578389916] 'agreement among raft nodes before linearized reading' (duration: 220.589293ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:33.517482Z","caller":"traceutil/trace.go:171","msg":"trace[1179256376] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4848; }","duration":"238.238603ms","start":"2026-04-27T12:08:33.279225Z","end":"2026-04-27T12:08:33.517463Z","steps":["trace[1179256376] 'agreement among raft nodes before linearized reading' (duration: 238.062748ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:33.517416Z","caller":"traceutil/trace.go:171","msg":"trace[2008268308] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"295.721081ms","start":"2026-04-27T12:08:33.221678Z","end":"2026-04-27T12:08:33.517399Z","steps":["trace[2008268308] 'process raft request' (duration: 295.043987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:45.643342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.140582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:08:45.643468Z","caller":"traceutil/trace.go:171","msg":"trace[1550650458] linearizableReadLoop","detail":"{readStateIndex:5189; appliedIndex:5188; }","duration":"470.2202ms","start":"2026-04-27T12:08:45.173231Z","end":"2026-04-27T12:08:45.643451Z","steps":["trace[1550650458] 'read index received' (duration: 142.957497ms)","trace[1550650458] 'applied index is now lower than readState.Index' (duration: 327.259803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:45.643619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.389204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T12:08:45.643707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.840568ms","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":"warn","ts":"2026-04-27T12:08:45.644003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.857086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T12:08:45.644029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.051142ms","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-27T12:08:45.644047Z","caller":"traceutil/trace.go:171","msg":"trace[1085747067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4998; }","duration":"470.436995ms","start":"2026-04-27T12:08:45.173206Z","end":"2026-04-27T12:08:45.643643Z","steps":["trace[1085747067] 'agreement among raft nodes before linearized reading' (duration: 470.328522ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:45.64409Z","caller":"traceutil/trace.go:171","msg":"trace[1454792724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4998; }","duration":"456.986339ms","start":"2026-04-27T12:08:45.187085Z","end":"2026-04-27T12:08:45.644071Z","steps":["trace[1454792724] 'agreement among raft nodes before linearized reading' (duration: 456.744864ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:45.644099Z","caller":"traceutil/trace.go:171","msg":"trace[957986177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4998; }","duration":"458.184974ms","start":"2026-04-27T12:08:45.185897Z","end":"2026-04-27T12:08:45.644082Z","steps":["trace[957986177] 'agreement among raft nodes before linearized reading' (duration: 457.996021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:45.644129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:08:45.187073Z","time spent":"457.05ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T12:08:45.644129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:08:45.173197Z","time spent":"470.911636ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T12:08:45.64414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:08:45.185874Z","time spent":"458.255205ms","remote":"127.0.0.1:39904","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":"info","ts":"2026-04-27T12:08:45.643984Z","caller":"traceutil/trace.go:171","msg":"trace[1478795906] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"474.933731ms","start":"2026-04-27T12:08:45.16853Z","end":"2026-04-27T12:08:45.643463Z","steps":["trace[1478795906] 'process raft request' (duration: 147.590575ms)","trace[1478795906] 'compare' (duration: 327.04504ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:45.644239Z","caller":"traceutil/trace.go:171","msg":"trace[630301847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4998; }","duration":"458.903729ms","start":"2026-04-27T12:08:45.184818Z","end":"2026-04-27T12:08:45.643722Z","steps":["trace[630301847] 'agreement among raft nodes before linearized reading' (duration: 458.834328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:45.644301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:08:45.184799Z","time spent":"459.487741ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T12:08:45.644351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:08:45.168515Z","time spent":"475.706488ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:08:46.550754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.31657ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-27T12:09:38.111659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2829} {"level":"info","ts":"2026-04-27T12:09:38.188077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2829,"took":"74.887205ms","hash":1034275920,"current-db-size-bytes":51527680,"current-db-size":"52 MB","current-db-size-in-use-bytes":28389376,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-27T12:09:38.188193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1034275920,"revision":2829,"compact-revision":-1} {"level":"info","ts":"2026-04-27T12:12:18.989064Z","caller":"traceutil/trace.go:171","msg":"trace[1609655394] linearizableReadLoop","detail":"{readStateIndex:6814; appliedIndex:6812; }","duration":"108.919323ms","start":"2026-04-27T12:12:18.880124Z","end":"2026-04-27T12:12:18.989043Z","steps":["trace[1609655394] 'read index received' (duration: 66.695465ms)","trace[1609655394] 'applied index is now lower than readState.Index' (duration: 42.223108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:12:18.989323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.175178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-27T12:12:18.98936Z","caller":"traceutil/trace.go:171","msg":"trace[1824454972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6573; }","duration":"109.266999ms","start":"2026-04-27T12:12:18.880083Z","end":"2026-04-27T12:12:18.98935Z","steps":["trace[1824454972] 'agreement among raft nodes before linearized reading' (duration: 109.133986ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:12:18.9893Z","caller":"traceutil/trace.go:171","msg":"trace[419956077] transaction","detail":"{read_only:false; response_revision:6573; number_of_response:1; }","duration":"127.694007ms","start":"2026-04-27T12:12:18.861569Z","end":"2026-04-27T12:12:18.989263Z","steps":["trace[419956077] 'process raft request' (duration: 127.34591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:12:18.989503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.006874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-27T12:12:18.989606Z","caller":"traceutil/trace.go:171","msg":"trace[1799600138] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6573; }","duration":"109.134367ms","start":"2026-04-27T12:12:18.880446Z","end":"2026-04-27T12:12:18.98958Z","steps":["trace[1799600138] 'agreement among raft nodes before linearized reading' (duration: 108.871401ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:14:38.120511Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5449} {"level":"info","ts":"2026-04-27T12:14:38.198486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5449,"took":"76.677799ms","hash":1544405130,"current-db-size-bytes":51527680,"current-db-size":"52 MB","current-db-size-in-use-bytes":18386944,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-27T12:14:38.198571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1544405130,"revision":5449,"compact-revision":2829}