{"level":"warn","ts":"2026-05-01T14:10:56.049041Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-01T14:10:56.049161Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-01T14:10:56.04925Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-01T14:10:56.049269Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-05-01T14:10:56.049292Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-01T14:10:56.050039Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-05-01T14:10:56.050136Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-01T14:10:56.05382Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.383847ms"} {"level":"info","ts":"2026-05-01T14:10:56.064898Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-05-01T14:10:56.065048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-05-01T14:10:56.065082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-05-01T14:10:56.065095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-01T14:10:56.06511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-05-01T14:10:56.065346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-05-01T14:10:56.073477Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-01T14:10:56.076677Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-01T14:10:56.078968Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-01T14:10:56.082232Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-01T14:10:56.082457Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-01T14:10:56.082791Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-01T14:10:56.082921Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-01T14:10:56.082935Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-01T14:10:56.083845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-05-01T14:10:56.084041Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-05-01T14:10:56.088563Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-01T14:10:56.088694Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-05-01T14:10:56.088775Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-05-01T14:10:56.089084Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-01T14:10:56.08915Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-01T14:10:56.365697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-05-01T14:10:56.365935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-01T14:10:56.366026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-05-01T14:10:56.366092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-05-01T14:10:56.366154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-05-01T14:10:56.366221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-05-01T14:10:56.36638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-05-01T14:10:56.367751Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-05-01T14:10:56.367789Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T14:10:56.367964Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T14:10:56.367945Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T14:10:56.368389Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-01T14:10:56.368464Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-01T14:10:56.369108Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T14:10:56.369247Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T14:10:56.369283Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T14:10:56.370213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-05-01T14:10:56.37266Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-01T14:11:16.638225Z","caller":"traceutil/trace.go:171","msg":"trace[1993922297] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"128.582901ms","start":"2026-05-01T14:11:16.509614Z","end":"2026-05-01T14:11:16.638197Z","steps":["trace[1993922297] 'process raft request' (duration: 128.402116ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:11:34.308087Z","caller":"traceutil/trace.go:171","msg":"trace[786778303] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"107.041993ms","start":"2026-05-01T14:11:34.201017Z","end":"2026-05-01T14:11:34.308059Z","steps":["trace[786778303] 'process raft request' (duration: 76.793989ms)","trace[786778303] 'compare' (duration: 30.121321ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:11:35.129415Z","caller":"traceutil/trace.go:171","msg":"trace[263812115] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"384.874698ms","start":"2026-05-01T14:11:34.744508Z","end":"2026-05-01T14:11:35.129383Z","steps":["trace[263812115] 'read index received' (duration: 384.036715ms)","trace[263812115] 'applied index is now lower than readState.Index' (duration: 837.093µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:11:35.129465Z","caller":"traceutil/trace.go:171","msg":"trace[986251756] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"404.979986ms","start":"2026-05-01T14:11:34.724454Z","end":"2026-05-01T14:11:35.129433Z","steps":["trace[986251756] 'process raft request' (duration: 404.307137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:11:35.129638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.101895ms","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-05-01T14:11:35.129723Z","caller":"traceutil/trace.go:171","msg":"trace[355146743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"385.242199ms","start":"2026-05-01T14:11:34.744466Z","end":"2026-05-01T14:11:35.129708Z","steps":["trace[355146743] 'agreement among raft nodes before linearized reading' (duration: 385.044923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:11:35.129767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:11:34.744445Z","time spent":"385.312812ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T14:11:35.130123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:11:34.724421Z","time spent":"405.117001ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T14:11:35.157028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.157478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-crkvz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-01T14:11:35.157105Z","caller":"traceutil/trace.go:171","msg":"trace[536130574] range","detail":"{range_begin:/registry/pods/kube-system/cilium-crkvz; range_end:; response_count:1; response_revision:581; }","duration":"293.279392ms","start":"2026-05-01T14:11:34.863806Z","end":"2026-05-01T14:11:35.157086Z","steps":["trace[536130574] 'agreement among raft nodes before linearized reading' (duration: 293.030785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:11:35.157319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.537175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-01T14:11:35.157346Z","caller":"traceutil/trace.go:171","msg":"trace[446503244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"176.564616ms","start":"2026-05-01T14:11:34.980775Z","end":"2026-05-01T14:11:35.15734Z","steps":["trace[446503244] 'agreement among raft nodes before linearized reading' (duration: 176.496034ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:12:16.723109Z","caller":"traceutil/trace.go:171","msg":"trace[440258984] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"109.06081ms","start":"2026-05-01T14:12:16.614022Z","end":"2026-05-01T14:12:16.723082Z","steps":["trace[440258984] 'process raft request' (duration: 108.883605ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:13:03.120105Z","caller":"traceutil/trace.go:171","msg":"trace[1239541847] linearizableReadLoop","detail":"{readStateIndex:1861; appliedIndex:1860; }","duration":"167.673764ms","start":"2026-05-01T14:13:02.952392Z","end":"2026-05-01T14:13:03.120066Z","steps":["trace[1239541847] 'read index received' (duration: 167.461358ms)","trace[1239541847] 'applied index is now lower than readState.Index' (duration: 211.095µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:13:03.120308Z","caller":"traceutil/trace.go:171","msg":"trace[1504333934] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"194.787099ms","start":"2026-05-01T14:13:02.925506Z","end":"2026-05-01T14:13:03.120293Z","steps":["trace[1504333934] 'process raft request' (duration: 194.304985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:13:03.120746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.316612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-01T14:13:03.120899Z","caller":"traceutil/trace.go:171","msg":"trace[1886998720] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1790; }","duration":"168.541748ms","start":"2026-05-01T14:13:02.952329Z","end":"2026-05-01T14:13:03.12087Z","steps":["trace[1886998720] 'agreement among raft nodes before linearized reading' (duration: 168.133777ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:13:03.547606Z","caller":"traceutil/trace.go:171","msg":"trace[328364060] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"201.441807ms","start":"2026-05-01T14:13:03.346131Z","end":"2026-05-01T14:13:03.547572Z","steps":["trace[328364060] 'process raft request' (duration: 200.813459ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:13:54.825914Z","caller":"traceutil/trace.go:171","msg":"trace[335799918] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"236.757046ms","start":"2026-05-01T14:13:54.589103Z","end":"2026-05-01T14:13:54.825859Z","steps":["trace[335799918] 'process raft request' (duration: 179.088598ms)","trace[335799918] 'compare' (duration: 57.442951ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:13:54.881972Z","caller":"traceutil/trace.go:171","msg":"trace[1582523022] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"288.992841ms","start":"2026-05-01T14:13:54.592956Z","end":"2026-05-01T14:13:54.881949Z","steps":["trace[1582523022] 'process raft request' (duration: 288.891149ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:14:45.986766Z","caller":"traceutil/trace.go:171","msg":"trace[490376571] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"153.919065ms","start":"2026-05-01T14:14:45.832822Z","end":"2026-05-01T14:14:45.986741Z","steps":["trace[490376571] 'process raft request' (duration: 153.75866ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:15:19.275933Z","caller":"traceutil/trace.go:171","msg":"trace[1399975575] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"139.876379ms","start":"2026-05-01T14:15:19.13603Z","end":"2026-05-01T14:15:19.275906Z","steps":["trace[1399975575] 'process raft request' (duration: 139.688483ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:15:19.351833Z","caller":"traceutil/trace.go:171","msg":"trace[1892531564] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"180.798623ms","start":"2026-05-01T14:15:19.170995Z","end":"2026-05-01T14:15:19.351794Z","steps":["trace[1892531564] 'process raft request' (duration: 154.358477ms)","trace[1892531564] 'compare' (duration: 26.119047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:15:19.79314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.826682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T14:15:19.793583Z","caller":"traceutil/trace.go:171","msg":"trace[533154207] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"247.450525ms","start":"2026-05-01T14:15:19.54611Z","end":"2026-05-01T14:15:19.793561Z","steps":["trace[533154207] 'process raft request' (duration: 126.80755ms)","trace[533154207] 'compare' (duration: 119.632267ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:18:39.76768Z","caller":"traceutil/trace.go:171","msg":"trace[1575384502] transaction","detail":"{read_only:false; response_revision:4121; number_of_response:1; }","duration":"148.474188ms","start":"2026-05-01T14:18:39.619171Z","end":"2026-05-01T14:18:39.767645Z","steps":["trace[1575384502] 'process raft request' (duration: 148.290023ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:18:39.771695Z","caller":"traceutil/trace.go:171","msg":"trace[1207774842] transaction","detail":"{read_only:false; response_revision:4122; number_of_response:1; }","duration":"151.446262ms","start":"2026-05-01T14:18:39.620212Z","end":"2026-05-01T14:18:39.771658Z","steps":["trace[1207774842] 'process raft request' (duration: 150.955348ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:18:50.05724Z","caller":"traceutil/trace.go:171","msg":"trace[1795446993] linearizableReadLoop","detail":"{readStateIndex:4346; appliedIndex:4345; }","duration":"242.23703ms","start":"2026-05-01T14:18:49.814968Z","end":"2026-05-01T14:18:50.057205Z","steps":["trace[1795446993] 'read index received' (duration: 242.068555ms)","trace[1795446993] 'applied index is now lower than readState.Index' (duration: 166.205µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:18:50.0575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.516897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-01T14:18:50.057549Z","caller":"traceutil/trace.go:171","msg":"trace[1507326143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4174; }","duration":"242.61424ms","start":"2026-05-01T14:18:49.81492Z","end":"2026-05-01T14:18:50.057535Z","steps":["trace[1507326143] 'agreement among raft nodes before linearized reading' (duration: 242.437825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:50.557916Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201974541575982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T14:18:51.787668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.259111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T14:18:51.787776Z","caller":"traceutil/trace.go:171","msg":"trace[641280113] transaction","detail":"{read_only:false; response_revision:4175; number_of_response:1; }","duration":"1.783505378s","start":"2026-05-01T14:18:50.00425Z","end":"2026-05-01T14:18:51.787756Z","steps":["trace[641280113] 'process raft request' (duration: 909.078313ms)","trace[641280113] 'compare' (duration: 873.154329ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:18:51.787833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:50.004213Z","time spent":"1.783589982s","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:18:51.789431Z","caller":"traceutil/trace.go:171","msg":"trace[1151027753] transaction","detail":"{read_only:false; response_revision:4176; number_of_response:1; }","duration":"1.731127698s","start":"2026-05-01T14:18:50.058281Z","end":"2026-05-01T14:18:51.789409Z","steps":["trace[1151027753] 'process raft request' (duration: 1.730633414s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:51.789532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:50.058256Z","time spent":"1.731228661s","remote":"127.0.0.1:41816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:18:51.789521Z","caller":"traceutil/trace.go:171","msg":"trace[272583512] transaction","detail":"{read_only:false; response_revision:4178; number_of_response:1; }","duration":"1.701300644s","start":"2026-05-01T14:18:50.088167Z","end":"2026-05-01T14:18:51.789468Z","steps":["trace[272583512] 'process raft request' (duration: 1.70112777s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:51.789602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.91052594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-01T14:18:51.789656Z","caller":"traceutil/trace.go:171","msg":"trace[500343512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4179; }","duration":"1.910649023s","start":"2026-05-01T14:18:49.878996Z","end":"2026-05-01T14:18:51.789645Z","steps":["trace[500343512] 'agreement among raft nodes before linearized reading' (duration: 1.910471568s)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:18:51.789399Z","caller":"traceutil/trace.go:171","msg":"trace[805231931] linearizableReadLoop","detail":"{readStateIndex:4351; appliedIndex:4346; }","duration":"1.732033424s","start":"2026-05-01T14:18:50.05733Z","end":"2026-05-01T14:18:51.789363Z","steps":["trace[805231931] 'read index received' (duration: 856.011064ms)","trace[805231931] 'applied index is now lower than readState.Index' (duration: 876.021199ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:18:51.789696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:49.878977Z","time spent":"1.910711386s","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-01T14:18:51.789738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:50.088151Z","time spent":"1.701481939s","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:18:51.789842Z","caller":"traceutil/trace.go:171","msg":"trace[2060430750] transaction","detail":"{read_only:false; response_revision:4177; number_of_response:1; }","duration":"1.728508784s","start":"2026-05-01T14:18:50.061306Z","end":"2026-05-01T14:18:51.789815Z","steps":["trace[2060430750] 'process raft request' (duration: 1.727868496s)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:18:51.789892Z","caller":"traceutil/trace.go:171","msg":"trace[543391699] transaction","detail":"{read_only:false; response_revision:4179; number_of_response:1; }","duration":"1.616441865s","start":"2026-05-01T14:18:50.173436Z","end":"2026-05-01T14:18:51.789878Z","steps":["trace[543391699] 'process raft request' (duration: 1.61589297s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:51.789958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:50.173404Z","time spent":"1.616518787s","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T14:18:51.789982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:50.061295Z","time spent":"1.728619977s","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T14:18:51.790316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690174229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T14:18:51.790354Z","caller":"traceutil/trace.go:171","msg":"trace[1411038450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4180; }","duration":"1.690264133s","start":"2026-05-01T14:18:50.100082Z","end":"2026-05-01T14:18:51.790346Z","steps":["trace[1411038450] 'agreement among raft nodes before linearized reading' (duration: 1.690160759s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:51.790375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:50.100056Z","time spent":"1.690314295s","remote":"127.0.0.1:41794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-01T14:18:51.790379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.239671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T14:18:51.79043Z","caller":"traceutil/trace.go:171","msg":"trace[661569286] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4180; }","duration":"247.330824ms","start":"2026-05-01T14:18:51.543082Z","end":"2026-05-01T14:18:51.790413Z","steps":["trace[661569286] 'agreement among raft nodes before linearized reading' (duration: 247.23125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:51.790472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.985719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-968c7\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-05-01T14:18:51.790508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.027857ms","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":"warn","ts":"2026-05-01T14:18:51.790535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.883726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T14:18:51.790541Z","caller":"traceutil/trace.go:171","msg":"trace[1835300629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4180; }","duration":"489.10316ms","start":"2026-05-01T14:18:51.301426Z","end":"2026-05-01T14:18:51.790529Z","steps":["trace[1835300629] 'agreement among raft nodes before linearized reading' (duration: 489.038228ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:18:51.790537Z","caller":"traceutil/trace.go:171","msg":"trace[1638910062] range","detail":"{range_begin:/registry/pods/openstack/keepalived-968c7; range_end:; response_count:1; response_revision:4180; }","duration":"383.156334ms","start":"2026-05-01T14:18:51.407364Z","end":"2026-05-01T14:18:51.79052Z","steps":["trace[1638910062] 'agreement among raft nodes before linearized reading' (duration: 382.891545ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:18:51.790572Z","caller":"traceutil/trace.go:171","msg":"trace[930821735] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4180; }","duration":"707.957388ms","start":"2026-05-01T14:18:51.082602Z","end":"2026-05-01T14:18:51.79056Z","steps":["trace[930821735] 'agreement among raft nodes before linearized reading' (duration: 707.890106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:51.790577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:51.301394Z","time spent":"489.178342ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-01T14:18:51.790599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:51.082582Z","time spent":"708.01205ms","remote":"127.0.0.1:36448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":9,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-05-01T14:18:51.790433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.782569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-01T14:18:51.790647Z","caller":"traceutil/trace.go:171","msg":"trace[812181483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4180; }","duration":"299.020405ms","start":"2026-05-01T14:18:51.49162Z","end":"2026-05-01T14:18:51.79064Z","steps":["trace[812181483] 'agreement among raft nodes before linearized reading' (duration: 298.700765ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:18:51.790658Z","caller":"traceutil/trace.go:171","msg":"trace[1701098467] transaction","detail":"{read_only:false; response_revision:4180; number_of_response:1; }","duration":"175.572384ms","start":"2026-05-01T14:18:51.615068Z","end":"2026-05-01T14:18:51.79064Z","steps":["trace[1701098467] 'process raft request' (duration: 175.10739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:51.79059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:51.407335Z","time spent":"383.243637ms","remote":"127.0.0.1:41994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-968c7\" "} {"level":"warn","ts":"2026-05-01T14:18:51.790706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.88885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-01T14:18:51.790751Z","caller":"traceutil/trace.go:171","msg":"trace[427599314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4180; }","duration":"299.966222ms","start":"2026-05-01T14:18:51.490772Z","end":"2026-05-01T14:18:51.790738Z","steps":["trace[427599314] 'agreement among raft nodes before linearized reading' (duration: 299.843999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:51.790808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641233737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T14:18:51.790835Z","caller":"traceutil/trace.go:171","msg":"trace[1215537034] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:4180; }","duration":"1.641307489s","start":"2026-05-01T14:18:50.149519Z","end":"2026-05-01T14:18:51.790827Z","steps":["trace[1215537034] 'agreement among raft nodes before linearized reading' (duration: 1.641248557s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:51.790861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:50.149493Z","time spent":"1.64136296s","remote":"127.0.0.1:42056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-05-01T14:18:51.790819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:51.490754Z","time spent":"300.052284ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T14:18:51.792125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.366201ms","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-05-01T14:18:51.792221Z","caller":"traceutil/trace.go:171","msg":"trace[127130839] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4180; }","duration":"655.506625ms","start":"2026-05-01T14:18:51.13669Z","end":"2026-05-01T14:18:51.792197Z","steps":["trace[127130839] 'agreement among raft nodes before linearized reading' (duration: 653.612541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:18:51.792367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:18:51.13667Z","time spent":"655.681619ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-01T14:19:14.622932Z","caller":"traceutil/trace.go:171","msg":"trace[735860663] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"143.978114ms","start":"2026-05-01T14:19:14.478921Z","end":"2026-05-01T14:19:14.622899Z","steps":["trace[735860663] 'process raft request' (duration: 141.860304ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:19:14.622992Z","caller":"traceutil/trace.go:171","msg":"trace[1818770601] linearizableReadLoop","detail":"{readStateIndex:5006; appliedIndex:5004; }","duration":"128.079975ms","start":"2026-05-01T14:19:14.494891Z","end":"2026-05-01T14:19:14.622971Z","steps":["trace[1818770601] 'read index received' (duration: 125.858082ms)","trace[1818770601] 'applied index is now lower than readState.Index' (duration: 2.220913ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:19:14.623181Z","caller":"traceutil/trace.go:171","msg":"trace[75363875] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"139.069687ms","start":"2026-05-01T14:19:14.484099Z","end":"2026-05-01T14:19:14.623169Z","steps":["trace[75363875] 'process raft request' (duration: 138.743827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:19:14.62339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.858456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-01T14:19:14.623505Z","caller":"traceutil/trace.go:171","msg":"trace[487000797] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4820; }","duration":"147.0014ms","start":"2026-05-01T14:19:14.476482Z","end":"2026-05-01T14:19:14.623483Z","steps":["trace[487000797] 'agreement among raft nodes before linearized reading' (duration: 146.733053ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:19:14.647526Z","caller":"traceutil/trace.go:171","msg":"trace[1689998106] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"105.405804ms","start":"2026-05-01T14:19:14.542084Z","end":"2026-05-01T14:19:14.64749Z","steps":["trace[1689998106] 'process raft request' (duration: 105.268581ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:19:14.647572Z","caller":"traceutil/trace.go:171","msg":"trace[253916240] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"147.434143ms","start":"2026-05-01T14:19:14.500078Z","end":"2026-05-01T14:19:14.647512Z","steps":["trace[253916240] 'process raft request' (duration: 147.130324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:19:14.647797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.794535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-01T14:19:14.647808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.036369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T14:19:14.6479Z","caller":"traceutil/trace.go:171","msg":"trace[981471401] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:4822; }","duration":"140.958309ms","start":"2026-05-01T14:19:14.506921Z","end":"2026-05-01T14:19:14.647879Z","steps":["trace[981471401] 'agreement among raft nodes before linearized reading' (duration: 140.756294ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:19:14.64791Z","caller":"traceutil/trace.go:171","msg":"trace[842277703] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4822; }","duration":"131.220294ms","start":"2026-05-01T14:19:14.516667Z","end":"2026-05-01T14:19:14.647887Z","steps":["trace[842277703] 'agreement among raft nodes before linearized reading' (duration: 130.954907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:19:14.647925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.90938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14495"} {"level":"info","ts":"2026-05-01T14:19:14.647988Z","caller":"traceutil/trace.go:171","msg":"trace[897920630] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4822; }","duration":"140.050274ms","start":"2026-05-01T14:19:14.507925Z","end":"2026-05-01T14:19:14.647975Z","steps":["trace[897920630] 'agreement among raft nodes before linearized reading' (duration: 139.730985ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:19:14.806078Z","caller":"traceutil/trace.go:171","msg":"trace[1703798605] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"126.742817ms","start":"2026-05-01T14:19:14.679297Z","end":"2026-05-01T14:19:14.806039Z","steps":["trace[1703798605] 'process raft request' (duration: 126.48919ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:19:19.947322Z","caller":"traceutil/trace.go:171","msg":"trace[362509556] linearizableReadLoop","detail":"{readStateIndex:5171; appliedIndex:5170; }","duration":"158.576178ms","start":"2026-05-01T14:19:19.788725Z","end":"2026-05-01T14:19:19.947302Z","steps":["trace[362509556] 'read index received' (duration: 158.508316ms)","trace[362509556] 'applied index is now lower than readState.Index' (duration: 67.022µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:19:19.947398Z","caller":"traceutil/trace.go:171","msg":"trace[1816086556] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"280.477668ms","start":"2026-05-01T14:19:19.666899Z","end":"2026-05-01T14:19:19.947377Z","steps":["trace[1816086556] 'process raft request' (duration: 280.272783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:19:19.947603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.860577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-01T14:19:19.947909Z","caller":"traceutil/trace.go:171","msg":"trace[331095354] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:4980; }","duration":"159.210957ms","start":"2026-05-01T14:19:19.788686Z","end":"2026-05-01T14:19:19.947897Z","steps":["trace[331095354] 'agreement among raft nodes before linearized reading' (duration: 158.775365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:19:19.956942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.242187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8ac25f05-35af-4bee-9eb8-9693ca23e640\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-01T14:19:19.957002Z","caller":"traceutil/trace.go:171","msg":"trace[172009467] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8ac25f05-35af-4bee-9eb8-9693ca23e640; range_end:; response_count:1; response_revision:4983; }","duration":"141.306179ms","start":"2026-05-01T14:19:19.815677Z","end":"2026-05-01T14:19:19.956983Z","steps":["trace[172009467] 'agreement among raft nodes before linearized reading' (duration: 141.144424ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:19:20.331364Z","caller":"traceutil/trace.go:171","msg":"trace[531430688] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"183.455385ms","start":"2026-05-01T14:19:20.14788Z","end":"2026-05-01T14:19:20.331336Z","steps":["trace[531430688] 'process raft request' (duration: 127.970344ms)","trace[531430688] 'compare' (duration: 55.190952ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:19:20.3459Z","caller":"traceutil/trace.go:171","msg":"trace[704866252] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"181.849418ms","start":"2026-05-01T14:19:20.164028Z","end":"2026-05-01T14:19:20.345877Z","steps":["trace[704866252] 'process raft request' (duration: 181.647512ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:19:32.057402Z","caller":"traceutil/trace.go:171","msg":"trace[821727360] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"120.420038ms","start":"2026-05-01T14:19:31.936942Z","end":"2026-05-01T14:19:32.057362Z","steps":["trace[821727360] 'process raft request' (duration: 69.502417ms)","trace[821727360] 'compare' (duration: 50.060737ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:19:32.7858Z","caller":"traceutil/trace.go:171","msg":"trace[1308013753] linearizableReadLoop","detail":"{readStateIndex:5430; appliedIndex:5429; }","duration":"145.896369ms","start":"2026-05-01T14:19:32.639859Z","end":"2026-05-01T14:19:32.785755Z","steps":["trace[1308013753] 'read index received' (duration: 145.627082ms)","trace[1308013753] 'applied index is now lower than readState.Index' (duration: 267.617µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:19:32.7859Z","caller":"traceutil/trace.go:171","msg":"trace[1017891365] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"166.296597ms","start":"2026-05-01T14:19:32.619552Z","end":"2026-05-01T14:19:32.785849Z","steps":["trace[1017891365] 'process raft request' (duration: 165.959086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:19:32.786667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.745794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T14:19:32.786775Z","caller":"traceutil/trace.go:171","msg":"trace[984149052] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5231; }","duration":"146.899608ms","start":"2026-05-01T14:19:32.639852Z","end":"2026-05-01T14:19:32.786751Z","steps":["trace[984149052] 'agreement among raft nodes before linearized reading' (duration: 146.101846ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:20:56.696107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2983} {"level":"info","ts":"2026-05-01T14:20:56.768014Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2983,"took":"69.93673ms","hash":822703310,"current-db-size-bytes":57888768,"current-db-size":"58 MB","current-db-size-in-use-bytes":33288192,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-01T14:20:56.76809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":822703310,"revision":2983,"compact-revision":-1} {"level":"info","ts":"2026-05-01T14:25:48.007098Z","caller":"traceutil/trace.go:171","msg":"trace[420627579] linearizableReadLoop","detail":"{readStateIndex:8523; appliedIndex:8521; }","duration":"188.037043ms","start":"2026-05-01T14:25:47.819047Z","end":"2026-05-01T14:25:48.007084Z","steps":["trace[420627579] 'read index received' (duration: 187.532158ms)","trace[420627579] 'applied index is now lower than readState.Index' (duration: 503.495µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:25:48.007307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.751972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-01T14:25:48.007328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.249698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T14:25:48.007356Z","caller":"traceutil/trace.go:171","msg":"trace[1392247317] transaction","detail":"{read_only:false; response_revision:8230; number_of_response:1; }","duration":"320.015453ms","start":"2026-05-01T14:25:47.687155Z","end":"2026-05-01T14:25:48.007171Z","steps":["trace[1392247317] 'process raft request' (duration: 319.721345ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:25:48.007414Z","caller":"traceutil/trace.go:171","msg":"trace[1385147317] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8230; }","duration":"188.363171ms","start":"2026-05-01T14:25:47.819031Z","end":"2026-05-01T14:25:48.007394Z","steps":["trace[1385147317] 'agreement among raft nodes before linearized reading' (duration: 188.124845ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:25:48.007369Z","caller":"traceutil/trace.go:171","msg":"trace[936355413] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8230; }","duration":"137.883645ms","start":"2026-05-01T14:25:47.86947Z","end":"2026-05-01T14:25:48.007354Z","steps":["trace[936355413] 'agreement among raft nodes before linearized reading' (duration: 137.746792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:25:48.007572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:25:47.687144Z","time spent":"320.326692ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:25:48.007013Z","caller":"traceutil/trace.go:171","msg":"trace[205339829] transaction","detail":"{read_only:false; response_revision:8229; number_of_response:1; }","duration":"320.434014ms","start":"2026-05-01T14:25:47.686522Z","end":"2026-05-01T14:25:48.006956Z","steps":["trace[205339829] 'process raft request' (duration: 320.144046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:25:48.007753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:25:47.686482Z","time spent":"321.152635ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:25:48.777431Z","caller":"traceutil/trace.go:171","msg":"trace[806310471] transaction","detail":"{read_only:false; response_revision:8234; number_of_response:1; }","duration":"331.938749ms","start":"2026-05-01T14:25:48.445431Z","end":"2026-05-01T14:25:48.777369Z","steps":["trace[806310471] 'process raft request' (duration: 252.850384ms)","trace[806310471] 'compare' (duration: 78.663002ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:25:48.777536Z","caller":"traceutil/trace.go:171","msg":"trace[292423926] transaction","detail":"{read_only:false; response_revision:8235; number_of_response:1; }","duration":"331.267841ms","start":"2026-05-01T14:25:48.446239Z","end":"2026-05-01T14:25:48.777507Z","steps":["trace[292423926] 'process raft request' (duration: 331.004654ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:25:48.777521Z","caller":"traceutil/trace.go:171","msg":"trace[1387407184] linearizableReadLoop","detail":"{readStateIndex:8529; appliedIndex:8526; }","duration":"230.251776ms","start":"2026-05-01T14:25:48.547229Z","end":"2026-05-01T14:25:48.777481Z","steps":["trace[1387407184] 'read index received' (duration: 151.213552ms)","trace[1387407184] 'applied index is now lower than readState.Index' (duration: 79.037194ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:25:48.777632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:25:48.445389Z","time spent":"332.140236ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T14:25:48.777638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:25:48.44623Z","time spent":"331.357043ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:25:48.777662Z","caller":"traceutil/trace.go:171","msg":"trace[1368123768] transaction","detail":"{read_only:false; response_revision:8236; number_of_response:1; }","duration":"277.766998ms","start":"2026-05-01T14:25:48.49986Z","end":"2026-05-01T14:25:48.777627Z","steps":["trace[1368123768] 'process raft request' (duration: 277.569123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:25:48.77783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.581846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-968c7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-01T14:25:48.7779Z","caller":"traceutil/trace.go:171","msg":"trace[1856185107] range","detail":"{range_begin:/registry/pods/openstack/keepalived-968c7; range_end:; response_count:1; response_revision:8236; }","duration":"230.711609ms","start":"2026-05-01T14:25:48.547165Z","end":"2026-05-01T14:25:48.777877Z","steps":["trace[1856185107] 'agreement among raft nodes before linearized reading' (duration: 230.467502ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:25:56.704723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6087} {"level":"info","ts":"2026-05-01T14:25:56.802199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6087,"took":"95.248452ms","hash":992035171,"current-db-size-bytes":57888768,"current-db-size":"58 MB","current-db-size-in-use-bytes":23523328,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-01T14:25:56.802279Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":992035171,"revision":6087,"compact-revision":2983} {"level":"info","ts":"2026-05-01T14:26:16.883768Z","caller":"traceutil/trace.go:171","msg":"trace[1765465536] transaction","detail":"{read_only:false; response_revision:8437; number_of_response:1; }","duration":"315.774122ms","start":"2026-05-01T14:26:16.567962Z","end":"2026-05-01T14:26:16.883736Z","steps":["trace[1765465536] 'process raft request' (duration: 315.620248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:26:16.883928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:26:16.567938Z","time spent":"315.912367ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:26:17.057576Z","caller":"traceutil/trace.go:171","msg":"trace[637206359] transaction","detail":"{read_only:false; response_revision:8438; number_of_response:1; }","duration":"488.594077ms","start":"2026-05-01T14:26:16.568744Z","end":"2026-05-01T14:26:17.057338Z","steps":["trace[637206359] 'process raft request' (duration: 488.33768ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:26:17.057673Z","caller":"traceutil/trace.go:171","msg":"trace[1092622691] linearizableReadLoop","detail":"{readStateIndex:8740; appliedIndex:8738; }","duration":"427.683784ms","start":"2026-05-01T14:26:16.629962Z","end":"2026-05-01T14:26:17.057646Z","steps":["trace[1092622691] 'read index received' (duration: 253.712409ms)","trace[1092622691] 'applied index is now lower than readState.Index' (duration: 173.970525ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:26:17.057736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:26:16.568725Z","time spent":"488.933046ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:26:17.057782Z","caller":"traceutil/trace.go:171","msg":"trace[525200247] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"323.774919ms","start":"2026-05-01T14:26:16.733998Z","end":"2026-05-01T14:26:17.057773Z","steps":["trace[525200247] 'process raft request' (duration: 323.282925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:26:17.0579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:26:16.733971Z","time spent":"323.839291ms","remote":"127.0.0.1:54120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T14:26:17.058117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.555368ms","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":"warn","ts":"2026-05-01T14:26:17.058226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.29525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-01T14:26:17.058269Z","caller":"traceutil/trace.go:171","msg":"trace[1154710903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8439; }","duration":"125.351692ms","start":"2026-05-01T14:26:16.932909Z","end":"2026-05-01T14:26:17.058261Z","steps":["trace[1154710903] 'agreement among raft nodes before linearized reading' (duration: 125.233589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:26:17.058271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.371863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-01T14:26:17.058311Z","caller":"traceutil/trace.go:171","msg":"trace[1343244233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8439; }","duration":"125.420864ms","start":"2026-05-01T14:26:16.932874Z","end":"2026-05-01T14:26:17.058295Z","steps":["trace[1343244233] 'agreement among raft nodes before linearized reading' (duration: 125.280131ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:26:17.058311Z","caller":"traceutil/trace.go:171","msg":"trace[96089578] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8439; }","duration":"125.685771ms","start":"2026-05-01T14:26:16.932499Z","end":"2026-05-01T14:26:17.058185Z","steps":["trace[96089578] 'agreement among raft nodes before linearized reading' (duration: 125.403704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:26:17.058205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.21154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-01T14:26:17.058524Z","caller":"traceutil/trace.go:171","msg":"trace[495504266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8439; }","duration":"428.546719ms","start":"2026-05-01T14:26:16.629954Z","end":"2026-05-01T14:26:17.058501Z","steps":["trace[495504266] 'agreement among raft nodes before linearized reading' (duration: 428.047115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:26:17.058606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:26:16.629891Z","time spent":"428.701103ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-01T14:26:29.086811Z","caller":"traceutil/trace.go:171","msg":"trace[1382634388] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8539; }","duration":"176.413834ms","start":"2026-05-01T14:26:28.910368Z","end":"2026-05-01T14:26:29.086782Z","steps":["trace[1382634388] 'process raft request' (duration: 176.226209ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:26:29.107192Z","caller":"traceutil/trace.go:171","msg":"trace[1475947515] linearizableReadLoop","detail":"{readStateIndex:8847; appliedIndex:8845; }","duration":"155.872525ms","start":"2026-05-01T14:26:28.95126Z","end":"2026-05-01T14:26:29.107132Z","steps":["trace[1475947515] 'read index received' (duration: 135.376036ms)","trace[1475947515] 'applied index is now lower than readState.Index' (duration: 20.494999ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:26:29.107292Z","caller":"traceutil/trace.go:171","msg":"trace[840653797] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"194.525876ms","start":"2026-05-01T14:26:28.912713Z","end":"2026-05-01T14:26:29.107239Z","steps":["trace[840653797] 'process raft request' (duration: 194.272389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:26:29.107787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.522532ms","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-05-01T14:26:29.107855Z","caller":"traceutil/trace.go:171","msg":"trace[1347871195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8540; }","duration":"156.627326ms","start":"2026-05-01T14:26:28.951216Z","end":"2026-05-01T14:26:29.107843Z","steps":["trace[1347871195] 'agreement among raft nodes before linearized reading' (duration: 156.471101ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:27:18.316806Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247d.wal"} {"level":"info","ts":"2026-05-01T14:28:35.564446Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-01T14:28:35.571596Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-01T14:28:35.57175Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-05-01T14:30:02.953997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201974541591789,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T14:30:02.956777Z","caller":"traceutil/trace.go:171","msg":"trace[1448373406] transaction","detail":"{read_only:false; response_revision:10329; number_of_response:1; }","duration":"797.084205ms","start":"2026-05-01T14:30:02.159678Z","end":"2026-05-01T14:30:02.956762Z","steps":["trace[1448373406] 'process raft request' (duration: 796.991533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:30:02.956912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:30:02.159654Z","time spent":"797.201229ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:30:02.975165Z","caller":"traceutil/trace.go:171","msg":"trace[528904035] linearizableReadLoop","detail":"{readStateIndex:10691; appliedIndex:10689; }","duration":"698.129742ms","start":"2026-05-01T14:30:02.277009Z","end":"2026-05-01T14:30:02.975139Z","steps":["trace[528904035] 'read index received' (duration: 679.67791ms)","trace[528904035] 'applied index is now lower than readState.Index' (duration: 18.450372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:30:02.975348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.325887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-01T14:30:02.975404Z","caller":"traceutil/trace.go:171","msg":"trace[769350184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10330; }","duration":"698.41617ms","start":"2026-05-01T14:30:02.276974Z","end":"2026-05-01T14:30:02.975391Z","steps":["trace[769350184] 'agreement among raft nodes before linearized reading' (duration: 698.278966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:30:02.975441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:30:02.276968Z","time spent":"698.454641ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-01T14:30:02.975411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.368358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-01T14:30:02.975526Z","caller":"traceutil/trace.go:171","msg":"trace[1898749741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10330; }","duration":"698.529043ms","start":"2026-05-01T14:30:02.276973Z","end":"2026-05-01T14:30:02.975502Z","steps":["trace[1898749741] 'agreement among raft nodes before linearized reading' (duration: 698.241435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:30:02.97558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:30:02.276956Z","time spent":"698.613576ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-01T14:30:02.976403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.750343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-01T14:30:02.976483Z","caller":"traceutil/trace.go:171","msg":"trace[620830722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10332; }","duration":"690.872977ms","start":"2026-05-01T14:30:02.285592Z","end":"2026-05-01T14:30:02.976465Z","steps":["trace[620830722] 'agreement among raft nodes before linearized reading' (duration: 690.677891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:30:02.976478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.48495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-01T14:30:02.976526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:30:02.285573Z","time spent":"690.944138ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-01T14:30:02.976539Z","caller":"traceutil/trace.go:171","msg":"trace[1156786421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10332; }","duration":"665.561151ms","start":"2026-05-01T14:30:02.310964Z","end":"2026-05-01T14:30:02.976525Z","steps":["trace[1156786421] 'agreement among raft nodes before linearized reading' (duration: 665.418447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:30:02.976571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:30:02.310957Z","time spent":"665.606903ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T14:30:02.976845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.427746ms","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-05-01T14:30:02.976903Z","caller":"traceutil/trace.go:171","msg":"trace[205610264] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10332; }","duration":"666.516298ms","start":"2026-05-01T14:30:02.310369Z","end":"2026-05-01T14:30:02.976885Z","steps":["trace[205610264] 'agreement among raft nodes before linearized reading' (duration: 666.371614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:30:02.976938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:30:02.310354Z","time spent":"666.5759ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-01T14:30:56.710804Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8284} {"level":"info","ts":"2026-05-01T14:30:56.785888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8284,"took":"72.789955ms","hash":2222375443,"current-db-size-bytes":57888768,"current-db-size":"58 MB","current-db-size-in-use-bytes":26157056,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-01T14:30:56.785956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2222375443,"revision":8284,"compact-revision":6087} {"level":"info","ts":"2026-05-01T14:31:21.39859Z","caller":"traceutil/trace.go:171","msg":"trace[1889967723] transaction","detail":"{read_only:false; response_revision:11093; number_of_response:1; }","duration":"137.992325ms","start":"2026-05-01T14:31:21.260572Z","end":"2026-05-01T14:31:21.398564Z","steps":["trace[1889967723] 'process raft request' (duration: 137.817221ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:31:21.438543Z","caller":"traceutil/trace.go:171","msg":"trace[1042674808] linearizableReadLoop","detail":"{readStateIndex:11475; appliedIndex:11472; }","duration":"122.060876ms","start":"2026-05-01T14:31:21.316445Z","end":"2026-05-01T14:31:21.438506Z","steps":["trace[1042674808] 'read index received' (duration: 82.079457ms)","trace[1042674808] 'applied index is now lower than readState.Index' (duration: 39.980409ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:31:21.438603Z","caller":"traceutil/trace.go:171","msg":"trace[1405437844] transaction","detail":"{read_only:false; response_revision:11094; number_of_response:1; }","duration":"169.190447ms","start":"2026-05-01T14:31:21.269385Z","end":"2026-05-01T14:31:21.438576Z","steps":["trace[1405437844] 'process raft request' (duration: 168.886709ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:31:21.438648Z","caller":"traceutil/trace.go:171","msg":"trace[876128016] transaction","detail":"{read_only:false; response_revision:11095; number_of_response:1; }","duration":"153.528644ms","start":"2026-05-01T14:31:21.285086Z","end":"2026-05-01T14:31:21.438614Z","steps":["trace[876128016] 'process raft request' (duration: 153.3727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:31:21.438803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.22265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-01T14:31:21.438849Z","caller":"traceutil/trace.go:171","msg":"trace[1198838173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11095; }","duration":"100.302271ms","start":"2026-05-01T14:31:21.338536Z","end":"2026-05-01T14:31:21.438838Z","steps":["trace[1198838173] 'agreement among raft nodes before linearized reading' (duration: 100.134457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:31:21.439031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.55853ms","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-05-01T14:31:21.439097Z","caller":"traceutil/trace.go:171","msg":"trace[896483426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11095; }","duration":"122.685724ms","start":"2026-05-01T14:31:21.316394Z","end":"2026-05-01T14:31:21.43908Z","steps":["trace[896483426] 'agreement among raft nodes before linearized reading' (duration: 122.272902ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:31:21.571098Z","caller":"traceutil/trace.go:171","msg":"trace[1078866375] transaction","detail":"{read_only:false; response_revision:11098; number_of_response:1; }","duration":"127.264844ms","start":"2026-05-01T14:31:21.443798Z","end":"2026-05-01T14:31:21.571063Z","steps":["trace[1078866375] 'process raft request' (duration: 126.848092ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:31:21.571113Z","caller":"traceutil/trace.go:171","msg":"trace[1923852448] transaction","detail":"{read_only:false; response_revision:11099; number_of_response:1; }","duration":"127.022877ms","start":"2026-05-01T14:31:21.444061Z","end":"2026-05-01T14:31:21.571084Z","steps":["trace[1923852448] 'process raft request' (duration: 126.844042ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:31:21.571658Z","caller":"traceutil/trace.go:171","msg":"trace[236200759] transaction","detail":"{read_only:false; response_revision:11100; number_of_response:1; }","duration":"127.47448ms","start":"2026-05-01T14:31:21.444164Z","end":"2026-05-01T14:31:21.571638Z","steps":["trace[236200759] 'process raft request' (duration: 126.864453ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:31:21.571939Z","caller":"traceutil/trace.go:171","msg":"trace[655833344] linearizableReadLoop","detail":"{readStateIndex:11481; appliedIndex:11475; }","duration":"115.155391ms","start":"2026-05-01T14:31:21.456765Z","end":"2026-05-01T14:31:21.571921Z","steps":["trace[655833344] 'read index received' (duration: 29.885403ms)","trace[655833344] 'applied index is now lower than readState.Index' (duration: 85.268958ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:31:21.57213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.355277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T14:31:21.572173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.270714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T14:31:21.572207Z","caller":"traceutil/trace.go:171","msg":"trace[1504268443] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11101; }","duration":"115.46833ms","start":"2026-05-01T14:31:21.456721Z","end":"2026-05-01T14:31:21.572189Z","steps":["trace[1504268443] 'agreement among raft nodes before linearized reading' (duration: 115.338876ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:31:21.572234Z","caller":"traceutil/trace.go:171","msg":"trace[656433925] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11101; }","duration":"115.365748ms","start":"2026-05-01T14:31:21.456852Z","end":"2026-05-01T14:31:21.572218Z","steps":["trace[656433925] 'agreement among raft nodes before linearized reading' (duration: 115.257434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:31:21.572655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.104596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-05-01T14:31:21.572717Z","caller":"traceutil/trace.go:171","msg":"trace[113650840] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11101; }","duration":"111.191219ms","start":"2026-05-01T14:31:21.461511Z","end":"2026-05-01T14:31:21.572702Z","steps":["trace[113650840] 'agreement among raft nodes before linearized reading' (duration: 111.044725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:31:21.572882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.913943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T14:31:21.572917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.813412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-01T14:31:21.572929Z","caller":"traceutil/trace.go:171","msg":"trace[1324383650] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11101; }","duration":"115.992355ms","start":"2026-05-01T14:31:21.456926Z","end":"2026-05-01T14:31:21.572918Z","steps":["trace[1324383650] 'agreement among raft nodes before linearized reading' (duration: 115.922713ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:31:21.57224Z","caller":"traceutil/trace.go:171","msg":"trace[895031408] transaction","detail":"{read_only:false; response_revision:11101; number_of_response:1; }","duration":"126.051769ms","start":"2026-05-01T14:31:21.446176Z","end":"2026-05-01T14:31:21.572227Z","steps":["trace[895031408] 'process raft request' (duration: 125.342439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:31:21.572984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.376227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T14:31:21.573053Z","caller":"traceutil/trace.go:171","msg":"trace[125107823] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11101; }","duration":"115.46498ms","start":"2026-05-01T14:31:21.457571Z","end":"2026-05-01T14:31:21.573036Z","steps":["trace[125107823] 'agreement among raft nodes before linearized reading' (duration: 115.372778ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:31:21.572967Z","caller":"traceutil/trace.go:171","msg":"trace[1910085510] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11101; }","duration":"114.887544ms","start":"2026-05-01T14:31:21.458065Z","end":"2026-05-01T14:31:21.572952Z","steps":["trace[1910085510] 'agreement among raft nodes before linearized reading' (duration: 114.75612ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:35:56.717593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10929} {"level":"info","ts":"2026-05-01T14:35:56.800586Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10929,"took":"80.304887ms","hash":820702292,"current-db-size-bytes":57888768,"current-db-size":"58 MB","current-db-size-in-use-bytes":27725824,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-01T14:35:56.800656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":820702292,"revision":10929,"compact-revision":8284} {"level":"info","ts":"2026-05-01T14:40:56.724861Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12972} {"level":"info","ts":"2026-05-01T14:40:56.804528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12972,"took":"75.997355ms","hash":2654502953,"current-db-size-bytes":57888768,"current-db-size":"58 MB","current-db-size-in-use-bytes":24023040,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-01T14:40:56.804663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2654502953,"revision":12972,"compact-revision":10929}