{"level":"warn","ts":"2026-04-22T18:21:27.029823Z","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-22T18:21:27.029912Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.74: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.74:2380","--initial-cluster=instance=https://199.19.213.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.74: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-22T18:21:27.029969Z","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-22T18:21:27.02998Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-04-22T18:21:27.030012Z","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-22T18:21:27.030574Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"]} {"level":"info","ts":"2026-04-22T18:21:27.030641Z","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.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.74: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-22T18:21:27.034447Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.64045ms"} {"level":"info","ts":"2026-04-22T18:21:27.04277Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d4171f3ea72ed207","cluster-id":"d40b55e346dc63dc"} {"level":"info","ts":"2026-04-22T18:21:27.042849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T18:21:27.042878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 0"} {"level":"info","ts":"2026-04-22T18:21:27.042906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d4171f3ea72ed207 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T18:21:27.042923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 1"} {"level":"info","ts":"2026-04-22T18:21:27.042977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"warn","ts":"2026-04-22T18:21:27.050861Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T18:21:27.052321Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T18:21:27.054077Z","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-22T18:21:27.056366Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d4171f3ea72ed207","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T18:21:27.056631Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d4171f3ea72ed207","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T18:21:27.056734Z","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-22T18:21:27.056849Z","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-22T18:21:27.056877Z","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-22T18:21:27.057172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"info","ts":"2026-04-22T18:21:27.057323Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","added-peer-id":"d4171f3ea72ed207","added-peer-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-04-22T18:21:27.062153Z","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-22T18:21:27.062254Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-04-22T18:21:27.062345Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-04-22T18:21:27.062454Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d4171f3ea72ed207","initial-advertise-peer-urls":["https://199.19.213.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T18:21:27.062509Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T18:21:27.343882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T18:21:27.343949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T18:21:27.343987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgPreVoteResp from d4171f3ea72ed207 at term 1"} {"level":"info","ts":"2026-04-22T18:21:27.344006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became candidate at term 2"} {"level":"info","ts":"2026-04-22T18:21:27.344017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgVoteResp from d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-04-22T18:21:27.344033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became leader at term 2"} {"level":"info","ts":"2026-04-22T18:21:27.344053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d4171f3ea72ed207 elected leader d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-04-22T18:21:27.344882Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:21:27.345749Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d4171f3ea72ed207","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.74:2379]}","request-path":"/0/members/d4171f3ea72ed207/attributes","cluster-id":"d40b55e346dc63dc","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T18:21:27.345805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T18:21:27.346166Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T18:21:27.34622Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T18:21:27.345762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T18:21:27.349114Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T18:21:27.349511Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.74:2379"} {"level":"info","ts":"2026-04-22T18:21:27.349765Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:21:27.34986Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:21:27.349892Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T18:21:32.350656Z","caller":"traceutil/trace.go:171","msg":"trace[1115123119] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"151.733368ms","start":"2026-04-22T18:21:32.198901Z","end":"2026-04-22T18:21:32.350635Z","steps":["trace[1115123119] 'read index received' (duration: 140.617262ms)","trace[1115123119] 'applied index is now lower than readState.Index' (duration: 11.115356ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:21:32.350752Z","caller":"traceutil/trace.go:171","msg":"trace[144590753] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"157.861111ms","start":"2026-04-22T18:21:32.192865Z","end":"2026-04-22T18:21:32.350726Z","steps":["trace[144590753] 'process raft request' (duration: 146.656255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:21:32.350839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.894139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:21:32.350901Z","caller":"traceutil/trace.go:171","msg":"trace[1947692060] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:285; }","duration":"151.99634ms","start":"2026-04-22T18:21:32.198895Z","end":"2026-04-22T18:21:32.350892Z","steps":["trace[1947692060] 'agreement among raft nodes before linearized reading' (duration: 151.845499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:21:32.775908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.339761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T18:21:32.776102Z","caller":"traceutil/trace.go:171","msg":"trace[1574050243] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:291; }","duration":"425.356675ms","start":"2026-04-22T18:21:32.350718Z","end":"2026-04-22T18:21:32.776074Z","steps":["trace[1574050243] 'read index received' (duration: 257.461478ms)","trace[1574050243] 'applied index is now lower than readState.Index' (duration: 167.894387ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:21:32.776182Z","caller":"traceutil/trace.go:171","msg":"trace[1415362325] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"576.774869ms","start":"2026-04-22T18:21:32.199397Z","end":"2026-04-22T18:21:32.776172Z","steps":["trace[1415362325] 'process raft request' (duration: 408.748821ms)","trace[1415362325] 'compare' (duration: 167.23584ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:21:32.776215Z","caller":"traceutil/trace.go:171","msg":"trace[1513753409] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"573.733197ms","start":"2026-04-22T18:21:32.20245Z","end":"2026-04-22T18:21:32.776184Z","steps":["trace[1513753409] 'process raft request' (duration: 573.536585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:21:32.776379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.119142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-22T18:21:32.776358Z","caller":"traceutil/trace.go:171","msg":"trace[982774357] transaction","detail":"{read_only:false; number_of_response:0; response_revision:287; }","duration":"479.597958ms","start":"2026-04-22T18:21:32.296728Z","end":"2026-04-22T18:21:32.776325Z","steps":["trace[982774357] 'process raft request' (duration: 479.311365ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:21:32.776416Z","caller":"traceutil/trace.go:171","msg":"trace[82551225] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:287; }","duration":"577.195343ms","start":"2026-04-22T18:21:32.199212Z","end":"2026-04-22T18:21:32.776407Z","steps":["trace[82551225] 'agreement among raft nodes before linearized reading' (duration: 577.117043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:21:32.776447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:21:32.199112Z","time spent":"577.329905ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-22T18:21:32.776463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:21:32.202433Z","time spent":"573.910239ms","remote":"127.0.0.1:40112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T18:21:32.776496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:21:32.296701Z","time spent":"479.73844ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T18:21:32.77629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:21:32.199385Z","time spent":"576.87948ms","remote":"127.0.0.1:39974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T18:21:32.783698Z","caller":"traceutil/trace.go:171","msg":"trace[2103396084] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"269.530914ms","start":"2026-04-22T18:21:32.514127Z","end":"2026-04-22T18:21:32.783658Z","steps":["trace[2103396084] 'process raft request' (duration: 269.416242ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:21:32.783776Z","caller":"traceutil/trace.go:171","msg":"trace[1982029482] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"269.465574ms","start":"2026-04-22T18:21:32.514302Z","end":"2026-04-22T18:21:32.783768Z","steps":["trace[1982029482] 'process raft request' (duration: 269.437093ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:21:32.78371Z","caller":"traceutil/trace.go:171","msg":"trace[225471459] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"269.541224ms","start":"2026-04-22T18:21:32.514144Z","end":"2026-04-22T18:21:32.783685Z","steps":["trace[225471459] 'process raft request' (duration: 269.472633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:21:32.783906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.264106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-22T18:21:32.783946Z","caller":"traceutil/trace.go:171","msg":"trace[1026329995] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:291; }","duration":"430.333176ms","start":"2026-04-22T18:21:32.353603Z","end":"2026-04-22T18:21:32.783936Z","steps":["trace[1026329995] 'agreement among raft nodes before linearized reading' (duration: 430.265296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:21:32.783972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:21:32.353588Z","time spent":"430.379758ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-22T18:21:32.784001Z","caller":"traceutil/trace.go:171","msg":"trace[83114403] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"269.786037ms","start":"2026-04-22T18:21:32.514205Z","end":"2026-04-22T18:21:32.783991Z","steps":["trace[83114403] 'process raft request' (duration: 269.456194ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:21:32.78397Z","caller":"traceutil/trace.go:171","msg":"trace[1248273749] transaction","detail":"{read_only:false; number_of_response:0; response_revision:289; }","duration":"269.813317ms","start":"2026-04-22T18:21:32.51415Z","end":"2026-04-22T18:21:32.783963Z","steps":["trace[1248273749] 'process raft request' (duration: 269.491673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:21:32.78409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.549497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-04-22T18:21:32.784136Z","caller":"traceutil/trace.go:171","msg":"trace[1446144221] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:291; }","duration":"428.657619ms","start":"2026-04-22T18:21:32.355467Z","end":"2026-04-22T18:21:32.784125Z","steps":["trace[1446144221] 'agreement among raft nodes before linearized reading' (duration: 428.484007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:21:32.784175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:21:32.355447Z","time spent":"428.72043ms","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":139,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-22T18:22:02.249686Z","caller":"traceutil/trace.go:171","msg":"trace[242630014] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"180.946449ms","start":"2026-04-22T18:22:02.068715Z","end":"2026-04-22T18:22:02.249661Z","steps":["trace[242630014] 'process raft request' (duration: 180.480661ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:22:02.66846Z","caller":"traceutil/trace.go:171","msg":"trace[190175288] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"349.904089ms","start":"2026-04-22T18:22:02.318489Z","end":"2026-04-22T18:22:02.668393Z","steps":["trace[190175288] 'read index received' (duration: 254.336415ms)","trace[190175288] 'applied index is now lower than readState.Index' (duration: 95.562424ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:22:02.668567Z","caller":"traceutil/trace.go:171","msg":"trace[2044075198] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"364.144447ms","start":"2026-04-22T18:22:02.304369Z","end":"2026-04-22T18:22:02.668513Z","steps":["trace[2044075198] 'process raft request' (duration: 268.555022ms)","trace[2044075198] 'compare' (duration: 95.252972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:22:02.668677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.041235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qz9zq\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-22T18:22:02.66877Z","caller":"traceutil/trace.go:171","msg":"trace[1512418571] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qz9zq; range_end:; response_count:1; response_revision:615; }","duration":"350.321396ms","start":"2026-04-22T18:22:02.318422Z","end":"2026-04-22T18:22:02.668743Z","steps":["trace[1512418571] 'agreement among raft nodes before linearized reading' (duration: 349.91906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:22:02.668832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:22:02.318404Z","time spent":"350.41881ms","remote":"127.0.0.1:40108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-qz9zq\" "} {"level":"warn","ts":"2026-04-22T18:22:02.66884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:22:02.304347Z","time spent":"364.343405ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T18:22:18.57101Z","caller":"traceutil/trace.go:171","msg":"trace[2067198028] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"139.326643ms","start":"2026-04-22T18:22:18.431608Z","end":"2026-04-22T18:22:18.570935Z","steps":["trace[2067198028] 'process raft request' (duration: 139.174438ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:23:09.869184Z","caller":"traceutil/trace.go:171","msg":"trace[990346352] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"158.202883ms","start":"2026-04-22T18:23:09.71095Z","end":"2026-04-22T18:23:09.869153Z","steps":["trace[990346352] 'process raft request' (duration: 157.927737ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:23:35.700788Z","caller":"traceutil/trace.go:171","msg":"trace[1813052454] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"218.212905ms","start":"2026-04-22T18:23:35.482548Z","end":"2026-04-22T18:23:35.700761Z","steps":["trace[1813052454] 'read index received' (duration: 218.137644ms)","trace[1813052454] 'applied index is now lower than readState.Index' (duration: 74.011µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:23:35.700899Z","caller":"traceutil/trace.go:171","msg":"trace[1059106964] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"219.145632ms","start":"2026-04-22T18:23:35.481708Z","end":"2026-04-22T18:23:35.700854Z","steps":["trace[1059106964] 'process raft request' (duration: 218.874557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:23:35.701015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.435929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T18:23:35.701133Z","caller":"traceutil/trace.go:171","msg":"trace[2102241905] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1846; }","duration":"218.583032ms","start":"2026-04-22T18:23:35.48253Z","end":"2026-04-22T18:23:35.701113Z","steps":["trace[2102241905] 'agreement among raft nodes before linearized reading' (duration: 218.366879ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:23:35.749973Z","caller":"traceutil/trace.go:171","msg":"trace[618131021] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"265.806775ms","start":"2026-04-22T18:23:35.484129Z","end":"2026-04-22T18:23:35.749936Z","steps":["trace[618131021] 'process raft request' (duration: 265.581951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:23:35.750065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.421143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T18:23:35.750195Z","caller":"traceutil/trace.go:171","msg":"trace[329815055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1847; }","duration":"262.491304ms","start":"2026-04-22T18:23:35.487607Z","end":"2026-04-22T18:23:35.750098Z","steps":["trace[329815055] 'agreement among raft nodes before linearized reading' (duration: 262.332591ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:23:35.925329Z","caller":"traceutil/trace.go:171","msg":"trace[551113551] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1918; }","duration":"114.694601ms","start":"2026-04-22T18:23:35.810609Z","end":"2026-04-22T18:23:35.925304Z","steps":["trace[551113551] 'read index received' (duration: 2.336804ms)","trace[551113551] 'applied index is now lower than readState.Index' (duration: 112.356997ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:23:35.925502Z","caller":"traceutil/trace.go:171","msg":"trace[982001075] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"124.96853ms","start":"2026-04-22T18:23:35.800515Z","end":"2026-04-22T18:23:35.925484Z","steps":["trace[982001075] 'process raft request' (duration: 109.2213ms)","trace[982001075] 'compare' (duration: 15.467625ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:23:35.925687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.049358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-22T18:23:35.926065Z","caller":"traceutil/trace.go:171","msg":"trace[793237997] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1850; }","duration":"115.460735ms","start":"2026-04-22T18:23:35.810583Z","end":"2026-04-22T18:23:35.926043Z","steps":["trace[793237997] 'agreement among raft nodes before linearized reading' (duration: 114.943235ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:24:24.025433Z","caller":"traceutil/trace.go:171","msg":"trace[982264404] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"110.946935ms","start":"2026-04-22T18:24:23.914465Z","end":"2026-04-22T18:24:24.025412Z","steps":["trace[982264404] 'read index received' (duration: 110.749512ms)","trace[982264404] 'applied index is now lower than readState.Index' (duration: 196.263µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:24:24.025675Z","caller":"traceutil/trace.go:171","msg":"trace[1718746468] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"289.800951ms","start":"2026-04-22T18:24:23.735847Z","end":"2026-04-22T18:24:24.025648Z","steps":["trace[1718746468] 'process raft request' (duration: 289.410506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:24:24.026623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.141272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T18:24:24.026744Z","caller":"traceutil/trace.go:171","msg":"trace[1308240264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2158; }","duration":"112.334405ms","start":"2026-04-22T18:24:23.914389Z","end":"2026-04-22T18:24:24.026724Z","steps":["trace[1308240264] 'agreement among raft nodes before linearized reading' (duration: 111.31159ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:24:24.163667Z","caller":"traceutil/trace.go:171","msg":"trace[633623427] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"132.390655ms","start":"2026-04-22T18:24:24.031245Z","end":"2026-04-22T18:24:24.163636Z","steps":["trace[633623427] 'process raft request' (duration: 132.299624ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:24:24.163929Z","caller":"traceutil/trace.go:171","msg":"trace[1330037205] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"133.291128ms","start":"2026-04-22T18:24:24.030606Z","end":"2026-04-22T18:24:24.163897Z","steps":["trace[1330037205] 'process raft request' (duration: 126.920292ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:24:24.163931Z","caller":"traceutil/trace.go:171","msg":"trace[510552730] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"132.835321ms","start":"2026-04-22T18:24:24.031077Z","end":"2026-04-22T18:24:24.163912Z","steps":["trace[510552730] 'process raft request' (duration: 132.331964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:25:38.998059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.924351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T18:25:38.998101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.713403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-22T18:25:38.998146Z","caller":"traceutil/trace.go:171","msg":"trace[2139392296] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2674; }","duration":"133.019443ms","start":"2026-04-22T18:25:38.865095Z","end":"2026-04-22T18:25:38.998115Z","steps":["trace[2139392296] 'count revisions from in-memory index tree' (duration: 132.8912ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:25:38.998174Z","caller":"traceutil/trace.go:171","msg":"trace[432733368] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2674; }","duration":"146.843734ms","start":"2026-04-22T18:25:38.851313Z","end":"2026-04-22T18:25:38.998157Z","steps":["trace[432733368] 'range keys from in-memory index tree' (duration: 146.53607ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:25:39.01906Z","caller":"traceutil/trace.go:171","msg":"trace[340079467] transaction","detail":"{read_only:false; response_revision:2675; number_of_response:1; }","duration":"139.065561ms","start":"2026-04-22T18:25:38.879985Z","end":"2026-04-22T18:25:39.019051Z","steps":["trace[340079467] 'process raft request' (duration: 138.965299ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:28:21.790649Z","caller":"traceutil/trace.go:171","msg":"trace[1682722153] transaction","detail":"{read_only:false; response_revision:3624; number_of_response:1; }","duration":"101.372351ms","start":"2026-04-22T18:28:21.689257Z","end":"2026-04-22T18:28:21.790629Z","steps":["trace[1682722153] 'process raft request' (duration: 101.31767ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:28:21.790799Z","caller":"traceutil/trace.go:171","msg":"trace[223594390] transaction","detail":"{read_only:false; response_revision:3623; number_of_response:1; }","duration":"115.604262ms","start":"2026-04-22T18:28:21.675185Z","end":"2026-04-22T18:28:21.79079Z","steps":["trace[223594390] 'process raft request' (duration: 67.007987ms)","trace[223594390] 'compare' (duration: 48.084334ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:29:43.382647Z","caller":"traceutil/trace.go:171","msg":"trace[1671736792] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"281.596384ms","start":"2026-04-22T18:29:43.101021Z","end":"2026-04-22T18:29:43.382617Z","steps":["trace[1671736792] 'process raft request' (duration: 281.414731ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:43.677886Z","caller":"traceutil/trace.go:171","msg":"trace[621635854] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"105.722794ms","start":"2026-04-22T18:29:43.572129Z","end":"2026-04-22T18:29:43.677852Z","steps":["trace[621635854] 'process raft request' (duration: 37.339556ms)","trace[621635854] 'compare' (duration: 67.91111ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:29:43.677964Z","caller":"traceutil/trace.go:171","msg":"trace[933766621] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"102.489789ms","start":"2026-04-22T18:29:43.575422Z","end":"2026-04-22T18:29:43.677911Z","steps":["trace[933766621] 'process raft request' (duration: 102.189664ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:43.678486Z","caller":"traceutil/trace.go:171","msg":"trace[1004499022] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"105.848576ms","start":"2026-04-22T18:29:43.572625Z","end":"2026-04-22T18:29:43.678473Z","steps":["trace[1004499022] 'process raft request' (duration: 104.89354ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:43.678603Z","caller":"traceutil/trace.go:171","msg":"trace[1508697713] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"104.607584ms","start":"2026-04-22T18:29:43.573987Z","end":"2026-04-22T18:29:43.678594Z","steps":["trace[1508697713] 'process raft request' (duration: 103.593547ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:44.053224Z","caller":"traceutil/trace.go:171","msg":"trace[884446433] linearizableReadLoop","detail":"{readStateIndex:4925; appliedIndex:4924; }","duration":"152.732594ms","start":"2026-04-22T18:29:43.900456Z","end":"2026-04-22T18:29:44.053189Z","steps":["trace[884446433] 'read index received' (duration: 137.878941ms)","trace[884446433] 'applied index is now lower than readState.Index' (duration: 14.852433ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:29:44.053547Z","caller":"traceutil/trace.go:171","msg":"trace[1610927060] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"169.286597ms","start":"2026-04-22T18:29:43.884133Z","end":"2026-04-22T18:29:44.053419Z","steps":["trace[1610927060] 'process raft request' (duration: 154.362912ms)","trace[1610927060] 'compare' (duration: 14.224414ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:29:44.053589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.103241ms","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-22T18:29:44.053737Z","caller":"traceutil/trace.go:171","msg":"trace[1993900958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4747; }","duration":"153.335255ms","start":"2026-04-22T18:29:43.900387Z","end":"2026-04-22T18:29:44.053722Z","steps":["trace[1993900958] 'agreement among raft nodes before linearized reading' (duration: 153.01226ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:46.685983Z","caller":"traceutil/trace.go:171","msg":"trace[1164776867] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"116.062663ms","start":"2026-04-22T18:29:46.569891Z","end":"2026-04-22T18:29:46.685954Z","steps":["trace[1164776867] 'process raft request' (duration: 115.538203ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:51.792824Z","caller":"traceutil/trace.go:171","msg":"trace[97771748] linearizableReadLoop","detail":"{readStateIndex:5051; appliedIndex:5050; }","duration":"104.76652ms","start":"2026-04-22T18:29:51.688038Z","end":"2026-04-22T18:29:51.792805Z","steps":["trace[97771748] 'read index received' (duration: 104.677647ms)","trace[97771748] 'applied index is now lower than readState.Index' (duration: 88.123µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:29:51.792881Z","caller":"traceutil/trace.go:171","msg":"trace[1419178632] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"155.643289ms","start":"2026-04-22T18:29:51.637213Z","end":"2026-04-22T18:29:51.792856Z","steps":["trace[1419178632] 'process raft request' (duration: 155.468156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:29:51.792987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.930602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-22T18:29:51.793021Z","caller":"traceutil/trace.go:171","msg":"trace[627225885] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4868; }","duration":"105.015804ms","start":"2026-04-22T18:29:51.687996Z","end":"2026-04-22T18:29:51.793012Z","steps":["trace[627225885] 'agreement among raft nodes before linearized reading' (duration: 104.894082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:29:55.739197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.517668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-22T18:29:55.739267Z","caller":"traceutil/trace.go:171","msg":"trace[871416392] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4906; }","duration":"116.67958ms","start":"2026-04-22T18:29:55.622572Z","end":"2026-04-22T18:29:55.739252Z","steps":["trace[871416392] 'range keys from in-memory index tree' (duration: 116.374874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:29:55.915119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.449234ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-22T18:29:55.915568Z","caller":"traceutil/trace.go:171","msg":"trace[2119759018] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"171.011214ms","start":"2026-04-22T18:29:55.744532Z","end":"2026-04-22T18:29:55.915543Z","steps":["trace[2119759018] 'process raft request' (duration: 170.827981ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:55.943234Z","caller":"traceutil/trace.go:171","msg":"trace[1634428668] linearizableReadLoop","detail":"{readStateIndex:5092; appliedIndex:5089; }","duration":"188.122721ms","start":"2026-04-22T18:29:55.755091Z","end":"2026-04-22T18:29:55.943214Z","steps":["trace[1634428668] 'read index received' (duration: 15.48955ms)","trace[1634428668] 'applied index is now lower than readState.Index' (duration: 172.632181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:29:55.943672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.575569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-22T18:29:55.943688Z","caller":"traceutil/trace.go:171","msg":"trace[46184857] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"188.983886ms","start":"2026-04-22T18:29:55.754676Z","end":"2026-04-22T18:29:55.94366Z","steps":["trace[46184857] 'process raft request' (duration: 188.356735ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:55.943724Z","caller":"traceutil/trace.go:171","msg":"trace[1576167744] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4908; }","duration":"188.666401ms","start":"2026-04-22T18:29:55.755045Z","end":"2026-04-22T18:29:55.943712Z","steps":["trace[1576167744] 'agreement among raft nodes before linearized reading' (duration: 188.412176ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:55.954718Z","caller":"traceutil/trace.go:171","msg":"trace[681459182] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"145.176839ms","start":"2026-04-22T18:29:55.809525Z","end":"2026-04-22T18:29:55.954702Z","steps":["trace[681459182] 'process raft request' (duration: 144.863494ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:56.075334Z","caller":"traceutil/trace.go:171","msg":"trace[523900154] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"115.303716ms","start":"2026-04-22T18:29:55.960011Z","end":"2026-04-22T18:29:56.075314Z","steps":["trace[523900154] 'process raft request' (duration: 115.232255ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:56.075396Z","caller":"traceutil/trace.go:171","msg":"trace[907381837] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"116.291292ms","start":"2026-04-22T18:29:55.959088Z","end":"2026-04-22T18:29:56.075379Z","steps":["trace[907381837] 'process raft request' (duration: 116.109609ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:29:56.075328Z","caller":"traceutil/trace.go:171","msg":"trace[506585257] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"116.393715ms","start":"2026-04-22T18:29:55.958895Z","end":"2026-04-22T18:29:56.075288Z","steps":["trace[506585257] 'process raft request' (duration: 69.0792ms)","trace[506585257] 'compare' (duration: 47.059711ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:31:27.443901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2931} {"level":"info","ts":"2026-04-22T18:31:27.542474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2931,"took":"95.68214ms","hash":1668376767,"current-db-size-bytes":54235136,"current-db-size":"54 MB","current-db-size-in-use-bytes":29532160,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-22T18:31:27.54259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1668376767,"revision":2931,"compact-revision":-1} {"level":"info","ts":"2026-04-22T18:31:55.129989Z","caller":"traceutil/trace.go:171","msg":"trace[817001358] linearizableReadLoop","detail":"{readStateIndex:6014; appliedIndex:6013; }","duration":"149.294072ms","start":"2026-04-22T18:31:54.980674Z","end":"2026-04-22T18:31:55.129968Z","steps":["trace[817001358] 'read index received' (duration: 128.697539ms)","trace[817001358] 'applied index is now lower than readState.Index' (duration: 20.595873ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:31:55.130345Z","caller":"traceutil/trace.go:171","msg":"trace[2123298789] transaction","detail":"{read_only:false; response_revision:5804; number_of_response:1; }","duration":"153.444724ms","start":"2026-04-22T18:31:54.976878Z","end":"2026-04-22T18:31:55.130323Z","steps":["trace[2123298789] 'process raft request' (duration: 132.541546ms)","trace[2123298789] 'compare' (duration: 20.040935ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:31:55.130333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.641267ms","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-22T18:31:55.130486Z","caller":"traceutil/trace.go:171","msg":"trace[666811358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5804; }","duration":"149.849559ms","start":"2026-04-22T18:31:54.980618Z","end":"2026-04-22T18:31:55.130468Z","steps":["trace[666811358] 'agreement among raft nodes before linearized reading' (duration: 149.564945ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:31:55.300852Z","caller":"traceutil/trace.go:171","msg":"trace[1636808796] linearizableReadLoop","detail":"{readStateIndex:6016; appliedIndex:6014; }","duration":"165.548279ms","start":"2026-04-22T18:31:55.135283Z","end":"2026-04-22T18:31:55.300831Z","steps":["trace[1636808796] 'read index received' (duration: 15.504916ms)","trace[1636808796] 'applied index is now lower than readState.Index' (duration: 150.042423ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T18:31:55.30109Z","caller":"traceutil/trace.go:171","msg":"trace[1698107678] transaction","detail":"{read_only:false; response_revision:5807; number_of_response:1; }","duration":"165.64157ms","start":"2026-04-22T18:31:55.135431Z","end":"2026-04-22T18:31:55.301072Z","steps":["trace[1698107678] 'process raft request' (duration: 165.351926ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:31:55.300924Z","caller":"traceutil/trace.go:171","msg":"trace[1212432259] transaction","detail":"{read_only:false; response_revision:5806; number_of_response:1; }","duration":"165.789382ms","start":"2026-04-22T18:31:55.135089Z","end":"2026-04-22T18:31:55.300879Z","steps":["trace[1212432259] 'process raft request' (duration: 165.550149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:31:55.301386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.084018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-22T18:31:55.301451Z","caller":"traceutil/trace.go:171","msg":"trace[2054743830] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5807; }","duration":"166.179399ms","start":"2026-04-22T18:31:55.135257Z","end":"2026-04-22T18:31:55.301436Z","steps":["trace[2054743830] 'agreement among raft nodes before linearized reading' (duration: 165.962716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:31:55.301383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.601924ms","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-22T18:31:55.301729Z","caller":"traceutil/trace.go:171","msg":"trace[1049503802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5807; }","duration":"117.025801ms","start":"2026-04-22T18:31:55.184687Z","end":"2026-04-22T18:31:55.301713Z","steps":["trace[1049503802] 'agreement among raft nodes before linearized reading' (duration: 116.506622ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:36:27.450721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5668} {"level":"info","ts":"2026-04-22T18:36:27.533419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5668,"took":"80.645854ms","hash":2985338013,"current-db-size-bytes":54235136,"current-db-size":"54 MB","current-db-size-in-use-bytes":18100224,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-22T18:36:27.533483Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2985338013,"revision":5668,"compact-revision":2931} {"level":"info","ts":"2026-04-22T18:37:43.687518Z","caller":"traceutil/trace.go:171","msg":"trace[866539388] transaction","detail":"{read_only:false; response_revision:7847; number_of_response:1; }","duration":"300.610278ms","start":"2026-04-22T18:37:43.386875Z","end":"2026-04-22T18:37:43.687485Z","steps":["trace[866539388] 'process raft request' (duration: 300.424153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:37:43.687929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:37:43.386856Z","time spent":"300.762982ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T18:37:43.688426Z","caller":"traceutil/trace.go:171","msg":"trace[1085420237] linearizableReadLoop","detail":"{readStateIndex:8137; appliedIndex:8137; }","duration":"298.378896ms","start":"2026-04-22T18:37:43.390035Z","end":"2026-04-22T18:37:43.688414Z","steps":["trace[1085420237] 'read index received' (duration: 298.372476ms)","trace[1085420237] 'applied index is now lower than readState.Index' (duration: 5.55µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:37:43.688572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.531301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-04-22T18:37:43.688601Z","caller":"traceutil/trace.go:171","msg":"trace[1457140613] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:7847; }","duration":"298.565042ms","start":"2026-04-22T18:37:43.390027Z","end":"2026-04-22T18:37:43.688592Z","steps":["trace[1457140613] 'agreement among raft nodes before linearized reading' (duration: 298.448228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:37:43.691302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.496513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-system/\" range_end:\"/registry/jobs/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T18:37:43.691386Z","caller":"traceutil/trace.go:171","msg":"trace[66923262] range","detail":"{range_begin:/registry/jobs/kube-system/; range_end:/registry/jobs/kube-system0; response_count:0; response_revision:7848; }","duration":"255.660027ms","start":"2026-04-22T18:37:43.435707Z","end":"2026-04-22T18:37:43.691367Z","steps":["trace[66923262] 'agreement among raft nodes before linearized reading' (duration: 255.494303ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:37:43.692012Z","caller":"traceutil/trace.go:171","msg":"trace[1667675656] transaction","detail":"{read_only:false; response_revision:7848; number_of_response:1; }","duration":"301.673517ms","start":"2026-04-22T18:37:43.390301Z","end":"2026-04-22T18:37:43.691975Z","steps":["trace[1667675656] 'process raft request' (duration: 300.708321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:37:43.692187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T18:37:43.390287Z","time spent":"301.794031ms","remote":"127.0.0.1:40180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T18:37:43.695454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.052843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-04-22T18:37:43.695545Z","caller":"traceutil/trace.go:171","msg":"trace[817926692] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:7848; }","duration":"246.203666ms","start":"2026-04-22T18:37:43.449323Z","end":"2026-04-22T18:37:43.695527Z","steps":["trace[817926692] 'agreement among raft nodes before linearized reading' (duration: 245.95554ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:37:45.906394Z","caller":"traceutil/trace.go:171","msg":"trace[1548901887] transaction","detail":"{read_only:false; response_revision:7858; number_of_response:1; }","duration":"197.533477ms","start":"2026-04-22T18:37:45.708829Z","end":"2026-04-22T18:37:45.906363Z","steps":["trace[1548901887] 'process raft request' (duration: 197.321401ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:37:45.909404Z","caller":"traceutil/trace.go:171","msg":"trace[1205748501] transaction","detail":"{read_only:false; response_revision:7859; number_of_response:1; }","duration":"200.175079ms","start":"2026-04-22T18:37:45.709196Z","end":"2026-04-22T18:37:45.909371Z","steps":["trace[1205748501] 'process raft request' (duration: 198.846133ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:37:45.909687Z","caller":"traceutil/trace.go:171","msg":"trace[1697314471] transaction","detail":"{read_only:false; response_revision:7860; number_of_response:1; }","duration":"199.237323ms","start":"2026-04-22T18:37:45.710407Z","end":"2026-04-22T18:37:45.909644Z","steps":["trace[1697314471] 'process raft request' (duration: 198.901204ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:37:45.909953Z","caller":"traceutil/trace.go:171","msg":"trace[466444276] transaction","detail":"{read_only:false; response_revision:7861; number_of_response:1; }","duration":"199.291954ms","start":"2026-04-22T18:37:45.710647Z","end":"2026-04-22T18:37:45.909939Z","steps":["trace[466444276] 'process raft request' (duration: 198.904524ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:37:45.910099Z","caller":"traceutil/trace.go:171","msg":"trace[1299899979] transaction","detail":"{read_only:false; response_revision:7862; number_of_response:1; }","duration":"198.586176ms","start":"2026-04-22T18:37:45.711503Z","end":"2026-04-22T18:37:45.910089Z","steps":["trace[1299899979] 'process raft request' (duration: 198.331739ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T18:37:45.912754Z","caller":"traceutil/trace.go:171","msg":"trace[1644783432] linearizableReadLoop","detail":"{readStateIndex:8152; appliedIndex:8147; }","duration":"173.492725ms","start":"2026-04-22T18:37:45.739232Z","end":"2026-04-22T18:37:45.912724Z","steps":["trace[1644783432] 'read index received' (duration: 166.997617ms)","trace[1644783432] 'applied index is now lower than readState.Index' (duration: 6.493538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T18:37:45.91308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.823905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-04-22T18:37:45.913131Z","caller":"traceutil/trace.go:171","msg":"trace[702570313] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:7862; }","duration":"132.938768ms","start":"2026-04-22T18:37:45.780179Z","end":"2026-04-22T18:37:45.913118Z","steps":["trace[702570313] 'agreement among raft nodes before linearized reading' (duration: 132.786704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:37:45.913212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.73771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:6129"} {"level":"info","ts":"2026-04-22T18:37:45.91328Z","caller":"traceutil/trace.go:171","msg":"trace[906853986] range","detail":"{range_begin:/registry/daemonsets/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7862; }","duration":"129.843963ms","start":"2026-04-22T18:37:45.783421Z","end":"2026-04-22T18:37:45.913265Z","steps":["trace[906853986] 'agreement among raft nodes before linearized reading' (duration: 129.605966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:37:45.913769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.62952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/\" range_end:\"/registry/deployments/monitoring0\" limit:500 ","response":"range_response_count:3 size:21500"} {"level":"info","ts":"2026-04-22T18:37:45.913804Z","caller":"traceutil/trace.go:171","msg":"trace[591465109] range","detail":"{range_begin:/registry/deployments/monitoring/; range_end:/registry/deployments/monitoring0; response_count:3; response_revision:7862; }","duration":"120.673431ms","start":"2026-04-22T18:37:45.793122Z","end":"2026-04-22T18:37:45.913796Z","steps":["trace[591465109] 'agreement among raft nodes before linearized reading' (duration: 120.465246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T18:37:45.914602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.345076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-22T18:37:45.91467Z","caller":"traceutil/trace.go:171","msg":"trace[1267011909] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:7862; }","duration":"175.484421ms","start":"2026-04-22T18:37:45.739167Z","end":"2026-04-22T18:37:45.914651Z","steps":["trace[1267011909] 'agreement among raft nodes before linearized reading' (duration: 173.640359ms)"],"step_count":1}