{"level":"warn","ts":"2026-04-08T10:47:29.743855Z","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-08T10:47:29.743951Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.146: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.146:2380","--initial-cluster=instance=https://199.19.213.146:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.146:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.146: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-08T10:47:29.744025Z","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-08T10:47:29.74404Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-04-08T10:47:29.74406Z","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-08T10:47:29.744857Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"]} {"level":"info","ts":"2026-04-08T10:47:29.744933Z","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.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.146: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-08T10:47:29.750222Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.062651ms"} {"level":"info","ts":"2026-04-08T10:47:29.759515Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c8e9c1aa9bb537b7","cluster-id":"933c0a8774274c15"} {"level":"info","ts":"2026-04-08T10:47:29.759623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T10:47:29.759648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 0"} {"level":"info","ts":"2026-04-08T10:47:29.759667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c8e9c1aa9bb537b7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T10:47:29.759676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 1"} {"level":"info","ts":"2026-04-08T10:47:29.75972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"warn","ts":"2026-04-08T10:47:29.763377Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T10:47:29.76494Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T10:47:29.766117Z","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-08T10:47:29.768557Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c8e9c1aa9bb537b7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T10:47:29.768771Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c8e9c1aa9bb537b7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T10:47:29.768872Z","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-08T10:47:29.769016Z","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-08T10:47:29.76905Z","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-08T10:47:29.770754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"info","ts":"2026-04-08T10:47:29.77125Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","added-peer-id":"c8e9c1aa9bb537b7","added-peer-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-04-08T10:47:29.773775Z","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-08T10:47:29.774023Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-04-08T10:47:29.774114Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-04-08T10:47:29.774266Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c8e9c1aa9bb537b7","initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T10:47:29.774335Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T10:47:30.760327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T10:47:30.760435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T10:47:30.760517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgPreVoteResp from c8e9c1aa9bb537b7 at term 1"} {"level":"info","ts":"2026-04-08T10:47:30.76058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became candidate at term 2"} {"level":"info","ts":"2026-04-08T10:47:30.760606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgVoteResp from c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-04-08T10:47:30.760627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became leader at term 2"} {"level":"info","ts":"2026-04-08T10:47:30.760646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c8e9c1aa9bb537b7 elected leader c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-04-08T10:47:30.762266Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:47:30.762481Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c8e9c1aa9bb537b7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.146:2379]}","request-path":"/0/members/c8e9c1aa9bb537b7/attributes","cluster-id":"933c0a8774274c15","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T10:47:30.762566Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T10:47:30.76261Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T10:47:30.762883Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T10:47:30.762949Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T10:47:30.763526Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:47:30.763683Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:47:30.76375Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:47:30.766518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T10:47:30.766695Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.146:2379"} {"level":"info","ts":"2026-04-08T10:48:02.399066Z","caller":"traceutil/trace.go:171","msg":"trace[2001048319] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"117.860182ms","start":"2026-04-08T10:48:02.281186Z","end":"2026-04-08T10:48:02.399046Z","steps":["trace[2001048319] 'process raft request' (duration: 108.730615ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:02.403145Z","caller":"traceutil/trace.go:171","msg":"trace[1902502942] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"119.55673ms","start":"2026-04-08T10:48:02.283557Z","end":"2026-04-08T10:48:02.403114Z","steps":["trace[1902502942] 'process raft request' (duration: 119.260821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:08.951833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.237415ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-08T10:48:08.985878Z","caller":"traceutil/trace.go:171","msg":"trace[827289176] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"135.853899ms","start":"2026-04-08T10:48:08.849979Z","end":"2026-04-08T10:48:08.985833Z","steps":["trace[827289176] 'read index received' (duration: 135.721575ms)","trace[827289176] 'applied index is now lower than readState.Index' (duration: 130.463µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:08.986031Z","caller":"traceutil/trace.go:171","msg":"trace[2111700560] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"160.935185ms","start":"2026-04-08T10:48:08.825039Z","end":"2026-04-08T10:48:08.985974Z","steps":["trace[2111700560] 'process raft request' (duration: 160.532224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:08.986321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.319901ms","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-08T10:48:08.986437Z","caller":"traceutil/trace.go:171","msg":"trace[1516324908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"136.468315ms","start":"2026-04-08T10:48:08.849934Z","end":"2026-04-08T10:48:08.986402Z","steps":["trace[1516324908] 'agreement among raft nodes before linearized reading' (duration: 136.179347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:09.489687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.47675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:48:09.489983Z","caller":"traceutil/trace.go:171","msg":"trace[1826175096] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"498.806938ms","start":"2026-04-08T10:48:08.991154Z","end":"2026-04-08T10:48:09.489961Z","steps":["trace[1826175096] 'process raft request' (duration: 498.683014ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:09.489989Z","caller":"traceutil/trace.go:171","msg":"trace[304038657] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"623.666747ms","start":"2026-04-08T10:48:08.866238Z","end":"2026-04-08T10:48:09.489905Z","steps":["trace[304038657] 'process raft request' (duration: 224.58021ms)","trace[304038657] 'compare' (duration: 397.372649ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:09.490069Z","caller":"traceutil/trace.go:171","msg":"trace[1784761998] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"503.945323ms","start":"2026-04-08T10:48:08.98607Z","end":"2026-04-08T10:48:09.490016Z","steps":["trace[1784761998] 'read index received' (duration: 104.772994ms)","trace[1784761998] 'applied index is now lower than readState.Index' (duration: 399.166959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:48:09.490243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:08.866214Z","time spent":"623.913534ms","remote":"127.0.0.1:52664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T10:48:09.490249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:08.99112Z","time spent":"498.928031ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:48:09.49038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.937306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T10:48:09.490429Z","caller":"traceutil/trace.go:171","msg":"trace[1481474130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"640.068659ms","start":"2026-04-08T10:48:08.850346Z","end":"2026-04-08T10:48:09.490415Z","steps":["trace[1481474130] 'agreement among raft nodes before linearized reading' (duration: 639.802872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:09.490471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:08.85032Z","time spent":"640.142361ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T10:48:24.613165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.952279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T10:48:24.613253Z","caller":"traceutil/trace.go:171","msg":"trace[1399757426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"110.085293ms","start":"2026-04-08T10:48:24.50315Z","end":"2026-04-08T10:48:24.613235Z","steps":["trace[1399757426] 'range keys from in-memory index tree' (duration: 109.64536ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:24.758889Z","caller":"traceutil/trace.go:171","msg":"trace[1645926838] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"141.836739ms","start":"2026-04-08T10:48:24.617024Z","end":"2026-04-08T10:48:24.758861Z","steps":["trace[1645926838] 'process raft request' (duration: 141.703334ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:37.315034Z","caller":"traceutil/trace.go:171","msg":"trace[1983025086] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"105.981449ms","start":"2026-04-08T10:49:37.209012Z","end":"2026-04-08T10:49:37.314994Z","steps":["trace[1983025086] 'read index received' (duration: 67.538808ms)","trace[1983025086] 'applied index is now lower than readState.Index' (duration: 38.441381ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:49:37.315351Z","caller":"traceutil/trace.go:171","msg":"trace[1345579862] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"110.012264ms","start":"2026-04-08T10:49:37.2053Z","end":"2026-04-08T10:49:37.315312Z","steps":["trace[1345579862] 'process raft request' (duration: 71.234923ms)","trace[1345579862] 'compare' (duration: 38.352179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:49:37.315502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.444392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T10:49:37.315657Z","caller":"traceutil/trace.go:171","msg":"trace[676417209] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1840; }","duration":"106.665178ms","start":"2026-04-08T10:49:37.20897Z","end":"2026-04-08T10:49:37.315635Z","steps":["trace[676417209] 'agreement among raft nodes before linearized reading' (duration: 106.287478ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:37.355549Z","caller":"traceutil/trace.go:171","msg":"trace[1655236566] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"139.108549ms","start":"2026-04-08T10:49:37.216401Z","end":"2026-04-08T10:49:37.355509Z","steps":["trace[1655236566] 'process raft request' (duration: 138.875942ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:37.596155Z","caller":"traceutil/trace.go:171","msg":"trace[1843811594] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"234.636621ms","start":"2026-04-08T10:49:37.361452Z","end":"2026-04-08T10:49:37.596089Z","steps":["trace[1843811594] 'process raft request' (duration: 194.044048ms)","trace[1843811594] 'compare' (duration: 40.284955ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:49:52.491837Z","caller":"traceutil/trace.go:171","msg":"trace[63811372] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"123.582498ms","start":"2026-04-08T10:49:52.368234Z","end":"2026-04-08T10:49:52.491817Z","steps":["trace[63811372] 'process raft request' (duration: 123.462235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:50:25.776655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.038381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:50:25.776845Z","caller":"traceutil/trace.go:171","msg":"trace[1325840553] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2234; }","duration":"141.70222ms","start":"2026-04-08T10:50:25.635125Z","end":"2026-04-08T10:50:25.776828Z","steps":["trace[1325840553] 'read index received' (duration: 82.177913ms)","trace[1325840553] 'applied index is now lower than readState.Index' (duration: 59.523107ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:50:25.776939Z","caller":"traceutil/trace.go:171","msg":"trace[276039869] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"331.640834ms","start":"2026-04-08T10:50:25.445276Z","end":"2026-04-08T10:50:25.776916Z","steps":["trace[276039869] 'process raft request' (duration: 143.269074ms)","trace[276039869] 'compare' (duration: 187.938498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:50:25.777033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:50:25.445261Z","time spent":"331.728847ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:50:25.777046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.929517ms","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-04-08T10:50:25.777132Z","caller":"traceutil/trace.go:171","msg":"trace[223979474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2152; }","duration":"142.029639ms","start":"2026-04-08T10:50:25.635078Z","end":"2026-04-08T10:50:25.777108Z","steps":["trace[223979474] 'agreement among raft nodes before linearized reading' (duration: 141.843163ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:50:25.777135Z","caller":"traceutil/trace.go:171","msg":"trace[1003079508] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"272.343083ms","start":"2026-04-08T10:50:25.504715Z","end":"2026-04-08T10:50:25.777058Z","steps":["trace[1003079508] 'process raft request' (duration: 272.051755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:50:26.005311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.194535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:50:26.005464Z","caller":"traceutil/trace.go:171","msg":"trace[991542931] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"160.640438ms","start":"2026-04-08T10:50:25.844809Z","end":"2026-04-08T10:50:26.00545Z","steps":["trace[991542931] 'process raft request' (duration: 53.234907ms)","trace[991542931] 'compare' (duration: 107.086962ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:51:06.670224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.754867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T10:51:06.670301Z","caller":"traceutil/trace.go:171","msg":"trace[3661275] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2549; }","duration":"154.888011ms","start":"2026-04-08T10:51:06.515396Z","end":"2026-04-08T10:51:06.670284Z","steps":["trace[3661275] 'range keys from in-memory index tree' (duration: 154.598313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:51:06.670551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.277552ms","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-08T10:51:06.670573Z","caller":"traceutil/trace.go:171","msg":"trace[673764372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2549; }","duration":"161.329914ms","start":"2026-04-08T10:51:06.509238Z","end":"2026-04-08T10:51:06.670568Z","steps":["trace[673764372] 'range keys from in-memory index tree' (duration: 161.185989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:51:40.465548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.863545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:51:40.465652Z","caller":"traceutil/trace.go:171","msg":"trace[1156963919] linearizableReadLoop","detail":"{readStateIndex:2860; appliedIndex:2859; }","duration":"109.270418ms","start":"2026-04-08T10:51:40.356369Z","end":"2026-04-08T10:51:40.465639Z","steps":["trace[1156963919] 'read index received' (duration: 41.342µs)","trace[1156963919] 'applied index is now lower than readState.Index' (duration: 109.227976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:51:40.465707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.34379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:51:40.465706Z","caller":"traceutil/trace.go:171","msg":"trace[1387002665] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"199.929875ms","start":"2026-04-08T10:51:40.265754Z","end":"2026-04-08T10:51:40.465684Z","steps":["trace[1387002665] 'process raft request' (duration: 57.847204ms)","trace[1387002665] 'compare' (duration: 141.621828ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:51:40.465731Z","caller":"traceutil/trace.go:171","msg":"trace[411179753] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2753; }","duration":"109.411202ms","start":"2026-04-08T10:51:40.356312Z","end":"2026-04-08T10:51:40.465723Z","steps":["trace[411179753] 'agreement among raft nodes before linearized reading' (duration: 109.35888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:51:40.70652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.056256ms","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-08T10:51:40.706589Z","caller":"traceutil/trace.go:171","msg":"trace[928809486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2753; }","duration":"139.170279ms","start":"2026-04-08T10:51:40.567406Z","end":"2026-04-08T10:51:40.706576Z","steps":["trace[928809486] 'range keys from in-memory index tree' (duration: 138.911471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:51:40.706519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.783581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T10:51:40.706729Z","caller":"traceutil/trace.go:171","msg":"trace[88029805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2753; }","duration":"136.048739ms","start":"2026-04-08T10:51:40.570672Z","end":"2026-04-08T10:51:40.706721Z","steps":["trace[88029805] 'range keys from in-memory index tree' (duration: 135.607527ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:51:40.823249Z","caller":"traceutil/trace.go:171","msg":"trace[297465384] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"126.056385ms","start":"2026-04-08T10:51:40.697166Z","end":"2026-04-08T10:51:40.823222Z","steps":["trace[297465384] 'process raft request' (duration: 125.89447ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:51:41.073356Z","caller":"traceutil/trace.go:171","msg":"trace[1350892772] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"363.272703ms","start":"2026-04-08T10:51:40.710054Z","end":"2026-04-08T10:51:41.073327Z","steps":["trace[1350892772] 'process raft request' (duration: 363.16477ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:51:41.07338Z","caller":"traceutil/trace.go:171","msg":"trace[1907508579] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"376.115227ms","start":"2026-04-08T10:51:40.697213Z","end":"2026-04-08T10:51:41.073328Z","steps":["trace[1907508579] 'process raft request' (duration: 361.131721ms)","trace[1907508579] 'compare' (duration: 14.711428ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:51:41.073414Z","caller":"traceutil/trace.go:171","msg":"trace[1002631219] linearizableReadLoop","detail":"{readStateIndex:2864; appliedIndex:2860; }","duration":"260.358275ms","start":"2026-04-08T10:51:40.813031Z","end":"2026-04-08T10:51:41.07339Z","steps":["trace[1002631219] 'read index received' (duration: 10.027515ms)","trace[1002631219] 'applied index is now lower than readState.Index' (duration: 250.32938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:51:41.07354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:51:40.710037Z","time spent":"363.408276ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T10:51:41.073622Z","caller":"traceutil/trace.go:171","msg":"trace[2076329154] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"362.187281ms","start":"2026-04-08T10:51:40.711421Z","end":"2026-04-08T10:51:41.073609Z","steps":["trace[2076329154] 'process raft request' (duration: 361.861302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:51:41.073681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:51:40.711415Z","time spent":"362.239912ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:51:41.073708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.669014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T10:51:41.073786Z","caller":"traceutil/trace.go:171","msg":"trace[1821601729] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2757; }","duration":"260.749936ms","start":"2026-04-08T10:51:40.813016Z","end":"2026-04-08T10:51:41.073766Z","steps":["trace[1821601729] 'agreement among raft nodes before linearized reading' (duration: 260.572421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:51:41.073555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:51:40.697188Z","time spent":"376.276782ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9386,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:51:41.073864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.427022ms","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-08T10:51:41.07397Z","caller":"traceutil/trace.go:171","msg":"trace[669339889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2757; }","duration":"218.569527ms","start":"2026-04-08T10:51:40.855359Z","end":"2026-04-08T10:51:41.073928Z","steps":["trace[669339889] 'agreement among raft nodes before linearized reading' (duration: 218.34034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:51:41.074088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.999248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T10:51:41.07415Z","caller":"traceutil/trace.go:171","msg":"trace[710480163] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2757; }","duration":"213.094341ms","start":"2026-04-08T10:51:40.861041Z","end":"2026-04-08T10:51:41.074135Z","steps":["trace[710480163] 'agreement among raft nodes before linearized reading' (duration: 212.956877ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:37.481878Z","caller":"traceutil/trace.go:171","msg":"trace[1770393943] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"117.816517ms","start":"2026-04-08T10:55:37.364035Z","end":"2026-04-08T10:55:37.481852Z","steps":["trace[1770393943] 'process raft request' (duration: 82.48756ms)","trace[1770393943] 'compare' (duration: 35.000638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:55:37.482037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.83633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-08T10:55:37.482066Z","caller":"traceutil/trace.go:171","msg":"trace[1553197687] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4803; }","duration":"116.906432ms","start":"2026-04-08T10:55:37.365153Z","end":"2026-04-08T10:55:37.48206Z","steps":["trace[1553197687] 'agreement among raft nodes before linearized reading' (duration: 116.779709ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:37.4819Z","caller":"traceutil/trace.go:171","msg":"trace[124455793] linearizableReadLoop","detail":"{readStateIndex:4985; appliedIndex:4980; }","duration":"116.696666ms","start":"2026-04-08T10:55:37.365187Z","end":"2026-04-08T10:55:37.481884Z","steps":["trace[124455793] 'read index received' (duration: 15.151813ms)","trace[124455793] 'applied index is now lower than readState.Index' (duration: 101.544263ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:55:37.482181Z","caller":"traceutil/trace.go:171","msg":"trace[1464074799] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"118.143986ms","start":"2026-04-08T10:55:37.364032Z","end":"2026-04-08T10:55:37.482176Z","steps":["trace[1464074799] 'process raft request' (duration: 117.728775ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:37.482234Z","caller":"traceutil/trace.go:171","msg":"trace[641691274] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"117.983122ms","start":"2026-04-08T10:55:37.364241Z","end":"2026-04-08T10:55:37.482224Z","steps":["trace[641691274] 'process raft request' (duration: 117.604131ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:37.482259Z","caller":"traceutil/trace.go:171","msg":"trace[1569480102] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"116.011466ms","start":"2026-04-08T10:55:37.366243Z","end":"2026-04-08T10:55:37.482254Z","steps":["trace[1569480102] 'process raft request' (duration: 115.618945ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:37.48233Z","caller":"traceutil/trace.go:171","msg":"trace[1093696961] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"118.170608ms","start":"2026-04-08T10:55:37.364151Z","end":"2026-04-08T10:55:37.482322Z","steps":["trace[1093696961] 'process raft request' (duration: 117.658363ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:37.482447Z","caller":"traceutil/trace.go:171","msg":"trace[354763008] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"118.218589ms","start":"2026-04-08T10:55:37.36422Z","end":"2026-04-08T10:55:37.482438Z","steps":["trace[354763008] 'process raft request' (duration: 117.607512ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:37.496544Z","caller":"traceutil/trace.go:171","msg":"trace[1090319628] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"109.82782ms","start":"2026-04-08T10:55:37.386686Z","end":"2026-04-08T10:55:37.496514Z","steps":["trace[1090319628] 'process raft request' (duration: 109.356147ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:37.670084Z","caller":"traceutil/trace.go:171","msg":"trace[1933694114] linearizableReadLoop","detail":"{readStateIndex:4990; appliedIndex:4988; }","duration":"137.243991ms","start":"2026-04-08T10:55:37.532817Z","end":"2026-04-08T10:55:37.670061Z","steps":["trace[1933694114] 'read index received' (duration: 117.507058ms)","trace[1933694114] 'applied index is now lower than readState.Index' (duration: 19.735382ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:55:37.670202Z","caller":"traceutil/trace.go:171","msg":"trace[1663208882] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"157.583961ms","start":"2026-04-08T10:55:37.512584Z","end":"2026-04-08T10:55:37.670168Z","steps":["trace[1663208882] 'process raft request' (duration: 157.390295ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:37.670383Z","caller":"traceutil/trace.go:171","msg":"trace[308910050] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"168.148811ms","start":"2026-04-08T10:55:37.502193Z","end":"2026-04-08T10:55:37.670342Z","steps":["trace[308910050] 'process raft request' (duration: 148.07581ms)","trace[308910050] 'compare' (duration: 19.558207ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:55:37.67048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.704239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"warn","ts":"2026-04-08T10:55:37.670505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.769062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:75474"} {"level":"info","ts":"2026-04-08T10:55:37.670583Z","caller":"traceutil/trace.go:171","msg":"trace[973306845] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4807; }","duration":"140.866194ms","start":"2026-04-08T10:55:37.529692Z","end":"2026-04-08T10:55:37.670558Z","steps":["trace[973306845] 'agreement among raft nodes before linearized reading' (duration: 140.530884ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:37.67058Z","caller":"traceutil/trace.go:171","msg":"trace[1315418831] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4807; }","duration":"133.860305ms","start":"2026-04-08T10:55:37.536704Z","end":"2026-04-08T10:55:37.670564Z","steps":["trace[1315418831] 'agreement among raft nodes before linearized reading' (duration: 133.531115ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:38.585328Z","caller":"traceutil/trace.go:171","msg":"trace[1988529812] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"102.586644ms","start":"2026-04-08T10:55:38.482724Z","end":"2026-04-08T10:55:38.585311Z","steps":["trace[1988529812] 'process raft request' (duration: 99.658341ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:55:42.748361Z","caller":"traceutil/trace.go:171","msg":"trace[85859922] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"218.101006ms","start":"2026-04-08T10:55:42.530237Z","end":"2026-04-08T10:55:42.748338Z","steps":["trace[85859922] 'process raft request' (duration: 125.643481ms)","trace[85859922] 'compare' (duration: 92.310691ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:55:42.818065Z","caller":"traceutil/trace.go:171","msg":"trace[955534166] linearizableReadLoop","detail":"{readStateIndex:5159; appliedIndex:5157; }","duration":"218.903728ms","start":"2026-04-08T10:55:42.59914Z","end":"2026-04-08T10:55:42.818043Z","steps":["trace[955534166] 'read index received' (duration: 56.766318ms)","trace[955534166] 'applied index is now lower than readState.Index' (duration: 162.13639ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:55:42.818096Z","caller":"traceutil/trace.go:171","msg":"trace[2131532484] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"222.848911ms","start":"2026-04-08T10:55:42.59523Z","end":"2026-04-08T10:55:42.818079Z","steps":["trace[2131532484] 'process raft request' (duration: 222.682407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:55:42.818253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.761195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T10:55:42.818288Z","caller":"traceutil/trace.go:171","msg":"trace[91531114] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4970; }","duration":"218.840986ms","start":"2026-04-08T10:55:42.599436Z","end":"2026-04-08T10:55:42.818277Z","steps":["trace[91531114] 'agreement among raft nodes before linearized reading' (duration: 218.724153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:55:42.818318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.149945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-901be062-a754-4749-bbb4-2b439a57372f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-08T10:55:42.818422Z","caller":"traceutil/trace.go:171","msg":"trace[1940074902] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-901be062-a754-4749-bbb4-2b439a57372f; range_end:; response_count:1; response_revision:4970; }","duration":"219.288219ms","start":"2026-04-08T10:55:42.599109Z","end":"2026-04-08T10:55:42.818397Z","steps":["trace[1940074902] 'agreement among raft nodes before linearized reading' (duration: 219.035592ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:56:00.399229Z","caller":"traceutil/trace.go:171","msg":"trace[350141732] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"147.407492ms","start":"2026-04-08T10:56:00.251799Z","end":"2026-04-08T10:56:00.399206Z","steps":["trace[350141732] 'process raft request' (duration: 147.281218ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:56:00.644805Z","caller":"traceutil/trace.go:171","msg":"trace[782189420] linearizableReadLoop","detail":"{readStateIndex:5544; appliedIndex:5543; }","duration":"223.088247ms","start":"2026-04-08T10:56:00.421689Z","end":"2026-04-08T10:56:00.644777Z","steps":["trace[782189420] 'read index received' (duration: 222.859431ms)","trace[782189420] 'applied index is now lower than readState.Index' (duration: 228.026µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:56:00.644883Z","caller":"traceutil/trace.go:171","msg":"trace[1720551517] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"251.396025ms","start":"2026-04-08T10:56:00.393452Z","end":"2026-04-08T10:56:00.644848Z","steps":["trace[1720551517] 'process raft request' (duration: 251.180459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:56:00.645205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.991087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-08T10:56:00.645244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.505529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T10:56:00.645316Z","caller":"traceutil/trace.go:171","msg":"trace[799448853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5344; }","duration":"208.118261ms","start":"2026-04-08T10:56:00.437139Z","end":"2026-04-08T10:56:00.645258Z","steps":["trace[799448853] 'agreement among raft nodes before linearized reading' (duration: 207.884714ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:56:00.645349Z","caller":"traceutil/trace.go:171","msg":"trace[1469972668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5344; }","duration":"223.683084ms","start":"2026-04-08T10:56:00.421652Z","end":"2026-04-08T10:56:00.645335Z","steps":["trace[1469972668] 'agreement among raft nodes before linearized reading' (duration: 223.308813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:56:02.44555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.414532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-901be062-a754-4749-bbb4-2b439a57372f\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-04-08T10:56:02.445718Z","caller":"traceutil/trace.go:171","msg":"trace[737225298] range","detail":"{range_begin:/registry/persistentvolumes/pvc-901be062-a754-4749-bbb4-2b439a57372f; range_end:; response_count:1; response_revision:5367; }","duration":"120.626258ms","start":"2026-04-08T10:56:02.325066Z","end":"2026-04-08T10:56:02.445692Z","steps":["trace[737225298] 'range keys from in-memory index tree' (duration: 120.236747ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:56:16.50948Z","caller":"traceutil/trace.go:171","msg":"trace[456037607] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"104.030935ms","start":"2026-04-08T10:56:16.405416Z","end":"2026-04-08T10:56:16.509446Z","steps":["trace[456037607] 'process raft request' (duration: 97.56702ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:56:17.063686Z","caller":"traceutil/trace.go:171","msg":"trace[411695324] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"243.903941ms","start":"2026-04-08T10:56:16.819754Z","end":"2026-04-08T10:56:17.063658Z","steps":["trace[411695324] 'process raft request' (duration: 166.841455ms)","trace[411695324] 'compare' (duration: 76.86168ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:56:17.06398Z","caller":"traceutil/trace.go:171","msg":"trace[390217564] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"104.755795ms","start":"2026-04-08T10:56:16.959162Z","end":"2026-04-08T10:56:17.063918Z","steps":["trace[390217564] 'process raft request' (duration: 104.450797ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:57:30.792133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3030} {"level":"info","ts":"2026-04-08T10:57:30.886373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3030,"took":"92.703007ms","hash":1223655062,"current-db-size-bytes":57286656,"current-db-size":"57 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-08T10:57:30.886443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1223655062,"revision":3030,"compact-revision":-1} {"level":"info","ts":"2026-04-08T10:58:36.818383Z","caller":"traceutil/trace.go:171","msg":"trace[825585090] transaction","detail":"{read_only:false; response_revision:6529; number_of_response:1; }","duration":"105.943918ms","start":"2026-04-08T10:58:36.712417Z","end":"2026-04-08T10:58:36.818361Z","steps":["trace[825585090] 'process raft request' (duration: 105.776333ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:58:58.188429Z","caller":"traceutil/trace.go:171","msg":"trace[1804587833] transaction","detail":"{read_only:false; response_revision:6644; number_of_response:1; }","duration":"102.220701ms","start":"2026-04-08T10:58:58.086146Z","end":"2026-04-08T10:58:58.188367Z","steps":["trace[1804587833] 'process raft request' (duration: 35.047247ms)","trace[1804587833] 'compare' (duration: 66.654419ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T11:01:38.055834Z","caller":"traceutil/trace.go:171","msg":"trace[1119656221] transaction","detail":"{read_only:false; response_revision:7844; number_of_response:1; }","duration":"162.007307ms","start":"2026-04-08T11:01:37.893799Z","end":"2026-04-08T11:01:38.055806Z","steps":["trace[1119656221] 'process raft request' (duration: 118.838025ms)","trace[1119656221] 'compare' (duration: 43.063299ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T11:02:30.800953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6143} {"level":"info","ts":"2026-04-08T11:02:30.903411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6143,"took":"99.898109ms","hash":3070701322,"current-db-size-bytes":57286656,"current-db-size":"57 MB","current-db-size-in-use-bytes":21254144,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-08T11:02:30.903521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3070701322,"revision":6143,"compact-revision":3030} {"level":"info","ts":"2026-04-08T11:02:39.016081Z","caller":"traceutil/trace.go:171","msg":"trace[1206477039] transaction","detail":"{read_only:false; response_revision:8344; number_of_response:1; }","duration":"299.290885ms","start":"2026-04-08T11:02:38.716764Z","end":"2026-04-08T11:02:39.016055Z","steps":["trace[1206477039] 'process raft request' (duration: 299.128391ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T11:02:39.170573Z","caller":"traceutil/trace.go:171","msg":"trace[751181018] transaction","detail":"{read_only:false; response_revision:8345; number_of_response:1; }","duration":"330.211801ms","start":"2026-04-08T11:02:38.84033Z","end":"2026-04-08T11:02:39.170542Z","steps":["trace[751181018] 'process raft request' (duration: 329.992335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:02:39.170747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T11:02:38.840304Z","time spent":"330.354615ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T11:02:39.178045Z","caller":"traceutil/trace.go:171","msg":"trace[1386883994] linearizableReadLoop","detail":"{readStateIndex:8643; appliedIndex:8640; }","duration":"111.94683ms","start":"2026-04-08T11:02:39.066072Z","end":"2026-04-08T11:02:39.178019Z","steps":["trace[1386883994] 'read index received' (duration: 104.299173ms)","trace[1386883994] 'applied index is now lower than readState.Index' (duration: 7.646627ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T11:02:39.178076Z","caller":"traceutil/trace.go:171","msg":"trace[77617809] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"239.596216ms","start":"2026-04-08T11:02:38.938456Z","end":"2026-04-08T11:02:39.178052Z","steps":["trace[77617809] 'process raft request' (duration: 239.443951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:02:39.17833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.243559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gl6sb\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T11:02:39.178407Z","caller":"traceutil/trace.go:171","msg":"trace[388447272] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gl6sb; range_end:; response_count:1; response_revision:8346; }","duration":"112.364912ms","start":"2026-04-08T11:02:39.066018Z","end":"2026-04-08T11:02:39.178383Z","steps":["trace[388447272] 'agreement among raft nodes before linearized reading' (duration: 112.159316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:02:49.775463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.966594ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-08T11:02:49.775553Z","caller":"traceutil/trace.go:171","msg":"trace[181043738] linearizableReadLoop","detail":"{readStateIndex:8703; appliedIndex:8702; }","duration":"353.766817ms","start":"2026-04-08T11:02:49.421771Z","end":"2026-04-08T11:02:49.775538Z","steps":["trace[181043738] 'read index received' (duration: 219.623879ms)","trace[181043738] 'applied index is now lower than readState.Index' (duration: 134.141058ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T11:02:49.775685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.899162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T11:02:49.775713Z","caller":"traceutil/trace.go:171","msg":"trace[1403392863] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8404; }","duration":"353.969993ms","start":"2026-04-08T11:02:49.421732Z","end":"2026-04-08T11:02:49.775702Z","steps":["trace[1403392863] 'agreement among raft nodes before linearized reading' (duration: 353.841209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:02:49.775753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T11:02:49.421712Z","time spent":"354.032705ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-08T11:02:50.745673Z","caller":"traceutil/trace.go:171","msg":"trace[1001830884] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"294.837138ms","start":"2026-04-08T11:02:50.450807Z","end":"2026-04-08T11:02:50.745644Z","steps":["trace[1001830884] 'process raft request' (duration: 294.692714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:02:51.570613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.810523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-08T11:02:51.570728Z","caller":"traceutil/trace.go:171","msg":"trace[409697477] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8413; }","duration":"113.975698ms","start":"2026-04-08T11:02:51.456726Z","end":"2026-04-08T11:02:51.570702Z","steps":["trace[409697477] 'range keys from in-memory index tree' (duration: 113.592327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:03:18.480372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.53259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T11:03:18.480603Z","caller":"traceutil/trace.go:171","msg":"trace[1426433786] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:8609; }","duration":"479.710075ms","start":"2026-04-08T11:03:18.000741Z","end":"2026-04-08T11:03:18.480451Z","steps":["trace[1426433786] 'count revisions from in-memory index tree' (duration: 479.442418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:03:18.480671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T11:03:18.000721Z","time spent":"479.933142ms","remote":"127.0.0.1:59818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-08T11:03:18.481188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.013207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T11:03:18.481269Z","caller":"traceutil/trace.go:171","msg":"trace[946233014] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8609; }","duration":"460.134809ms","start":"2026-04-08T11:03:18.021115Z","end":"2026-04-08T11:03:18.48125Z","steps":["trace[946233014] 'range keys from in-memory index tree' (duration: 459.845532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:03:18.481327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T11:03:18.021099Z","time spent":"460.219162ms","remote":"127.0.0.1:52696","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":"info","ts":"2026-04-08T11:03:18.826791Z","caller":"traceutil/trace.go:171","msg":"trace[2145287510] linearizableReadLoop","detail":"{readStateIndex:8917; appliedIndex:8916; }","duration":"169.351815ms","start":"2026-04-08T11:03:18.657404Z","end":"2026-04-08T11:03:18.826756Z","steps":["trace[2145287510] 'read index received' (duration: 169.122549ms)","trace[2145287510] 'applied index is now lower than readState.Index' (duration: 228.066µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T11:03:18.826887Z","caller":"traceutil/trace.go:171","msg":"trace[1897515297] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"341.757498ms","start":"2026-04-08T11:03:18.485088Z","end":"2026-04-08T11:03:18.826846Z","steps":["trace[1897515297] 'process raft request' (duration: 341.506981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:03:18.827012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.593652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T11:03:18.827068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T11:03:18.485066Z","time spent":"341.908222ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T11:03:18.827096Z","caller":"traceutil/trace.go:171","msg":"trace[1342479305] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8610; }","duration":"169.744656ms","start":"2026-04-08T11:03:18.657329Z","end":"2026-04-08T11:03:18.827073Z","steps":["trace[1342479305] 'agreement among raft nodes before linearized reading' (duration: 169.590282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:03:31.412649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.630908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-08T11:03:31.412766Z","caller":"traceutil/trace.go:171","msg":"trace[211717790] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8713; }","duration":"293.001007ms","start":"2026-04-08T11:03:31.119748Z","end":"2026-04-08T11:03:31.412749Z","steps":["trace[211717790] 'process raft request' (duration: 143.144503ms)","trace[211717790] 'compare' (duration: 149.520375ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T11:03:31.430607Z","caller":"traceutil/trace.go:171","msg":"trace[1599794218] linearizableReadLoop","detail":"{readStateIndex:9028; appliedIndex:9026; }","duration":"255.066303ms","start":"2026-04-08T11:03:31.175517Z","end":"2026-04-08T11:03:31.430583Z","steps":["trace[1599794218] 'read index received' (duration: 87.363434ms)","trace[1599794218] 'applied index is now lower than readState.Index' (duration: 167.701909ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T11:03:31.430653Z","caller":"traceutil/trace.go:171","msg":"trace[1869605708] transaction","detail":"{read_only:false; response_revision:8714; number_of_response:1; }","duration":"282.417307ms","start":"2026-04-08T11:03:31.148203Z","end":"2026-04-08T11:03:31.43062Z","steps":["trace[1869605708] 'process raft request' (duration: 282.249172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T11:03:31.430748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.213997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T11:03:31.430782Z","caller":"traceutil/trace.go:171","msg":"trace[643444239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8714; }","duration":"255.301759ms","start":"2026-04-08T11:03:31.17547Z","end":"2026-04-08T11:03:31.430772Z","steps":["trace[643444239] 'agreement among raft nodes before linearized reading' (duration: 255.206657ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T11:03:31.658933Z","caller":"traceutil/trace.go:171","msg":"trace[2040372338] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"114.159404ms","start":"2026-04-08T11:03:31.544756Z","end":"2026-04-08T11:03:31.658915Z","steps":["trace[2040372338] 'process raft request' (duration: 104.366536ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T11:03:31.659117Z","caller":"traceutil/trace.go:171","msg":"trace[1103319804] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"114.343099ms","start":"2026-04-08T11:03:31.54476Z","end":"2026-04-08T11:03:31.659103Z","steps":["trace[1103319804] 'process raft request' (duration: 114.126963ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T11:03:31.936491Z","caller":"traceutil/trace.go:171","msg":"trace[1142788659] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"128.321894ms","start":"2026-04-08T11:03:31.808144Z","end":"2026-04-08T11:03:31.936466Z","steps":["trace[1142788659] 'process raft request' (duration: 128.131209ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T11:03:33.711572Z","caller":"traceutil/trace.go:171","msg":"trace[2100664086] linearizableReadLoop","detail":"{readStateIndex:9051; appliedIndex:9050; }","duration":"107.633569ms","start":"2026-04-08T11:03:33.603907Z","end":"2026-04-08T11:03:33.71154Z","steps":["trace[2100664086] 'read index received' (duration: 90.270696ms)","trace[2100664086] 'applied index is now lower than readState.Index' (duration: 17.361853ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T11:03:33.711611Z","caller":"traceutil/trace.go:171","msg":"trace[1217946263] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"108.861602ms","start":"2026-04-08T11:03:33.602699Z","end":"2026-04-08T11:03:33.711561Z","steps":["trace[1217946263] 'process raft request' (duration: 91.541501ms)","trace[1217946263] 'compare' (duration: 17.165286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T11:03:33.712272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.345328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228480"} {"level":"info","ts":"2026-04-08T11:03:33.712353Z","caller":"traceutil/trace.go:171","msg":"trace[241913252] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8737; }","duration":"108.464342ms","start":"2026-04-08T11:03:33.603871Z","end":"2026-04-08T11:03:33.712335Z","steps":["trace[241913252] 'agreement among raft nodes before linearized reading' (duration: 107.813474ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T11:04:20.052411Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002533.wal"} {"level":"info","ts":"2026-04-08T11:04:33.980694Z","caller":"traceutil/trace.go:171","msg":"trace[1593568548] transaction","detail":"{read_only:false; response_revision:9328; number_of_response:1; }","duration":"107.727081ms","start":"2026-04-08T11:04:33.872931Z","end":"2026-04-08T11:04:33.980659Z","steps":["trace[1593568548] 'process raft request' (duration: 41.3291ms)","trace[1593568548] 'compare' (duration: 66.153553ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T11:04:45.617031Z","caller":"traceutil/trace.go:171","msg":"trace[1666930865] transaction","detail":"{read_only:false; response_revision:9395; number_of_response:1; }","duration":"137.658868ms","start":"2026-04-08T11:04:45.479344Z","end":"2026-04-08T11:04:45.617003Z","steps":["trace[1666930865] 'process raft request' (duration: 104.911911ms)","trace[1666930865] 'compare' (duration: 32.623803ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T11:05:15.462252Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c8e9c1aa9bb537b7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T11:05:15.468772Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T11:05:15.468898Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T11:07:30.808266Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8171} {"level":"info","ts":"2026-04-08T11:07:30.870514Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8171,"took":"60.341668ms","hash":2025913188,"current-db-size-bytes":57286656,"current-db-size":"57 MB","current-db-size-in-use-bytes":28631040,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-08T11:07:30.870574Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2025913188,"revision":8171,"compact-revision":6143} {"level":"info","ts":"2026-04-08T11:12:30.814032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10726} {"level":"info","ts":"2026-04-08T11:12:30.897421Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10726,"took":"81.330189ms","hash":2434904834,"current-db-size-bytes":57286656,"current-db-size":"57 MB","current-db-size-in-use-bytes":20869120,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-08T11:12:30.897487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2434904834,"revision":10726,"compact-revision":8171}