{"level":"warn","ts":"2026-03-20T10:15:03.124682Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T10:15:03.124802Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.123: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.123:2380","--initial-cluster=instance=https://199.19.213.123:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.123:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.123: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-03-20T10:15:03.124893Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T10:15:03.124914Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.123:2380"]} {"level":"info","ts":"2026-03-20T10:15:03.124942Z","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-03-20T10:15:03.125752Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"]} {"level":"info","ts":"2026-03-20T10:15:03.125873Z","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.123:2380"],"listen-peer-urls":["https://199.19.213.123:2380"],"advertise-client-urls":["https://199.19.213.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.123: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-03-20T10:15:03.129503Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.378802ms"} {"level":"info","ts":"2026-03-20T10:15:03.142726Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3e4829a3cabe4fce","cluster-id":"b469cfed817d8605"} {"level":"info","ts":"2026-03-20T10:15:03.142838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T10:15:03.142864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became follower at term 0"} {"level":"info","ts":"2026-03-20T10:15:03.142878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3e4829a3cabe4fce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T10:15:03.142887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became follower at term 1"} {"level":"info","ts":"2026-03-20T10:15:03.142942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=(4487882812132577230)"} {"level":"warn","ts":"2026-03-20T10:15:03.148487Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T10:15:03.149828Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T10:15:03.150848Z","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-03-20T10:15:03.152679Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3e4829a3cabe4fce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T10:15:03.15316Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3e4829a3cabe4fce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T10:15:03.153421Z","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-03-20T10:15:03.153573Z","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-03-20T10:15:03.154547Z","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-03-20T10:15:03.154791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=(4487882812132577230)"} {"level":"info","ts":"2026-03-20T10:15:03.15558Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b469cfed817d8605","local-member-id":"3e4829a3cabe4fce","added-peer-id":"3e4829a3cabe4fce","added-peer-peer-urls":["https://199.19.213.123:2380"]} {"level":"info","ts":"2026-03-20T10:15:03.159187Z","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-03-20T10:15:03.159344Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.123:2380"} {"level":"info","ts":"2026-03-20T10:15:03.159515Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.123:2380"} {"level":"info","ts":"2026-03-20T10:15:03.160156Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T10:15:03.159934Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3e4829a3cabe4fce","initial-advertise-peer-urls":["https://199.19.213.123:2380"],"listen-peer-urls":["https://199.19.213.123:2380"],"advertise-client-urls":["https://199.19.213.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T10:15:04.143783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T10:15:04.143852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T10:15:04.143896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce received MsgPreVoteResp from 3e4829a3cabe4fce at term 1"} {"level":"info","ts":"2026-03-20T10:15:04.143915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became candidate at term 2"} {"level":"info","ts":"2026-03-20T10:15:04.143928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce received MsgVoteResp from 3e4829a3cabe4fce at term 2"} {"level":"info","ts":"2026-03-20T10:15:04.143955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became leader at term 2"} {"level":"info","ts":"2026-03-20T10:15:04.143974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3e4829a3cabe4fce elected leader 3e4829a3cabe4fce at term 2"} {"level":"info","ts":"2026-03-20T10:15:04.144916Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3e4829a3cabe4fce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.123:2379]}","request-path":"/0/members/3e4829a3cabe4fce/attributes","cluster-id":"b469cfed817d8605","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T10:15:04.144994Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T10:15:04.145027Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T10:15:04.145211Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T10:15:04.14527Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T10:15:04.145618Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:15:04.146825Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b469cfed817d8605","local-member-id":"3e4829a3cabe4fce","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:15:04.146913Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:15:04.146948Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:15:04.147144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-20T10:15:04.148561Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.123:2379"} {"level":"warn","ts":"2026-03-20T10:15:44.458002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.959706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-20T10:15:44.458136Z","caller":"traceutil/trace.go:171","msg":"trace[2123246459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"231.176511ms","start":"2026-03-20T10:15:44.226934Z","end":"2026-03-20T10:15:44.458111Z","steps":["trace[2123246459] 'range keys from in-memory index tree' (duration: 230.820022ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:15:44.602117Z","caller":"traceutil/trace.go:171","msg":"trace[314116915] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"138.821428ms","start":"2026-03-20T10:15:44.463266Z","end":"2026-03-20T10:15:44.602088Z","steps":["trace[314116915] 'process raft request' (duration: 138.646384ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:15:46.403046Z","caller":"traceutil/trace.go:171","msg":"trace[1879757870] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"102.229023ms","start":"2026-03-20T10:15:46.300795Z","end":"2026-03-20T10:15:46.403024Z","steps":["trace[1879757870] 'process raft request' (duration: 98.490242ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:15:46.403417Z","caller":"traceutil/trace.go:171","msg":"trace[1332577734] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"101.632078ms","start":"2026-03-20T10:15:46.301736Z","end":"2026-03-20T10:15:46.403368Z","steps":["trace[1332577734] 'read index received' (duration: 8.173938ms)","trace[1332577734] 'applied index is now lower than readState.Index' (duration: 93.45545ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:15:46.403611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.860163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:15:46.403717Z","caller":"traceutil/trace.go:171","msg":"trace[1874931645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"102.032937ms","start":"2026-03-20T10:15:46.301659Z","end":"2026-03-20T10:15:46.403692Z","steps":["trace[1874931645] 'agreement among raft nodes before linearized reading' (duration: 101.854093ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:16:04.435731Z","caller":"traceutil/trace.go:171","msg":"trace[1611072520] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"177.184154ms","start":"2026-03-20T10:16:04.258522Z","end":"2026-03-20T10:16:04.435706Z","steps":["trace[1611072520] 'process raft request' (duration: 177.026321ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:16:56.421404Z","caller":"traceutil/trace.go:171","msg":"trace[2118952900] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"188.497817ms","start":"2026-03-20T10:16:56.232871Z","end":"2026-03-20T10:16:56.421369Z","steps":["trace[2118952900] 'process raft request' (duration: 188.256131ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:16:56.84266Z","caller":"traceutil/trace.go:171","msg":"trace[125231595] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"195.880912ms","start":"2026-03-20T10:16:56.646741Z","end":"2026-03-20T10:16:56.842622Z","steps":["trace[125231595] 'process raft request' (duration: 153.766335ms)","trace[125231595] 'compare' (duration: 41.958954ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:16:56.843342Z","caller":"traceutil/trace.go:171","msg":"trace[625447438] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"183.201801ms","start":"2026-03-20T10:16:56.66011Z","end":"2026-03-20T10:16:56.843312Z","steps":["trace[625447438] 'process raft request' (duration: 183.018256ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:09.164559Z","caller":"traceutil/trace.go:171","msg":"trace[1950013570] transaction","detail":"{read_only:false; response_revision:1674; number_of_response:1; }","duration":"110.284437ms","start":"2026-03-20T10:17:09.054251Z","end":"2026-03-20T10:17:09.164536Z","steps":["trace[1950013570] 'process raft request' (duration: 110.101362ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:09.491037Z","caller":"traceutil/trace.go:171","msg":"trace[1538994469] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"190.845444ms","start":"2026-03-20T10:17:09.300171Z","end":"2026-03-20T10:17:09.491016Z","steps":["trace[1538994469] 'process raft request' (duration: 185.597959ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:35.265881Z","caller":"traceutil/trace.go:171","msg":"trace[1825541933] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"151.406521ms","start":"2026-03-20T10:17:35.114422Z","end":"2026-03-20T10:17:35.265828Z","steps":["trace[1825541933] 'process raft request' (duration: 123.081016ms)","trace[1825541933] 'compare' (duration: 28.08893ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:17:41.095943Z","caller":"traceutil/trace.go:171","msg":"trace[1043548216] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"100.485176ms","start":"2026-03-20T10:17:40.995416Z","end":"2026-03-20T10:17:41.095901Z","steps":["trace[1043548216] 'process raft request' (duration: 100.273231ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:47.227865Z","caller":"traceutil/trace.go:171","msg":"trace[200599406] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"113.749633ms","start":"2026-03-20T10:17:47.114094Z","end":"2026-03-20T10:17:47.227843Z","steps":["trace[200599406] 'process raft request' (duration: 113.578599ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:47.257014Z","caller":"traceutil/trace.go:171","msg":"trace[969208987] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"139.166359ms","start":"2026-03-20T10:17:47.117822Z","end":"2026-03-20T10:17:47.256989Z","steps":["trace[969208987] 'process raft request' (duration: 138.980365ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:47.468441Z","caller":"traceutil/trace.go:171","msg":"trace[1487162629] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1951; }","duration":"180.630797ms","start":"2026-03-20T10:17:47.287775Z","end":"2026-03-20T10:17:47.468406Z","steps":["trace[1487162629] 'read index received' (duration: 131.025605ms)","trace[1487162629] 'applied index is now lower than readState.Index' (duration: 49.602672ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:17:47.468525Z","caller":"traceutil/trace.go:171","msg":"trace[65399883] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"206.983697ms","start":"2026-03-20T10:17:47.261496Z","end":"2026-03-20T10:17:47.46848Z","steps":["trace[65399883] 'process raft request' (duration: 157.179169ms)","trace[65399883] 'compare' (duration: 49.414519ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:17:47.469014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.952896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.123\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-20T10:17:47.469061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.421207ms","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-03-20T10:17:47.46912Z","caller":"traceutil/trace.go:171","msg":"trace[1527839971] range","detail":"{range_begin:/registry/masterleases/199.19.213.123; range_end:; response_count:1; response_revision:1875; }","duration":"181.370455ms","start":"2026-03-20T10:17:47.287731Z","end":"2026-03-20T10:17:47.469101Z","steps":["trace[1527839971] 'agreement among raft nodes before linearized reading' (duration: 180.854353ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:17:47.469137Z","caller":"traceutil/trace.go:171","msg":"trace[1035678819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1875; }","duration":"145.560631ms","start":"2026-03-20T10:17:47.323555Z","end":"2026-03-20T10:17:47.469115Z","steps":["trace[1035678819] 'agreement among raft nodes before linearized reading' (duration: 145.150121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:17:47.46972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.720427ms","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-03-20T10:17:47.469867Z","caller":"traceutil/trace.go:171","msg":"trace[1457082760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1875; }","duration":"122.879741ms","start":"2026-03-20T10:17:47.34692Z","end":"2026-03-20T10:17:47.4698Z","steps":["trace[1457082760] 'agreement among raft nodes before linearized reading' (duration: 122.445621ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:19:11.856321Z","caller":"traceutil/trace.go:171","msg":"trace[2002081055] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"331.827894ms","start":"2026-03-20T10:19:11.524442Z","end":"2026-03-20T10:19:11.85627Z","steps":["trace[2002081055] 'process raft request' (duration: 281.900307ms)","trace[2002081055] 'compare' (duration: 49.704971ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:19:11.857188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:19:11.524402Z","time spent":"332.041119ms","remote":"127.0.0.1:39538","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-03-20T10:19:12.221801Z","caller":"traceutil/trace.go:171","msg":"trace[586129484] linearizableReadLoop","detail":"{readStateIndex:2588; appliedIndex:2587; }","duration":"302.456309ms","start":"2026-03-20T10:19:11.919302Z","end":"2026-03-20T10:19:12.221759Z","steps":["trace[586129484] 'read index received' (duration: 263.802024ms)","trace[586129484] 'applied index is now lower than readState.Index' (duration: 38.652665ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:19:12.222125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.793678ms","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-03-20T10:19:12.222173Z","caller":"traceutil/trace.go:171","msg":"trace[131772250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2485; }","duration":"302.918651ms","start":"2026-03-20T10:19:11.919239Z","end":"2026-03-20T10:19:12.222158Z","steps":["trace[131772250] 'agreement among raft nodes before linearized reading' (duration: 302.667804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:19:12.222211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:19:11.919215Z","time spent":"302.986823ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T10:19:12.222066Z","caller":"traceutil/trace.go:171","msg":"trace[1773672432] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"303.29279ms","start":"2026-03-20T10:19:11.918735Z","end":"2026-03-20T10:19:12.222028Z","steps":["trace[1773672432] 'process raft request' (duration: 264.389097ms)","trace[1773672432] 'compare' (duration: 38.162474ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:19:12.222442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:19:11.918717Z","time spent":"303.580936ms","remote":"127.0.0.1:39538","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-03-20T10:22:07.078009Z","caller":"traceutil/trace.go:171","msg":"trace[418354139] linearizableReadLoop","detail":"{readStateIndex:3681; appliedIndex:3680; }","duration":"212.65216ms","start":"2026-03-20T10:22:06.865336Z","end":"2026-03-20T10:22:07.077988Z","steps":["trace[418354139] 'read index received' (duration: 212.395544ms)","trace[418354139] 'applied index is now lower than readState.Index' (duration: 255.876µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:22:07.078128Z","caller":"traceutil/trace.go:171","msg":"trace[972046498] transaction","detail":"{read_only:false; response_revision:3536; number_of_response:1; }","duration":"286.634945ms","start":"2026-03-20T10:22:06.791459Z","end":"2026-03-20T10:22:07.078094Z","steps":["trace[972046498] 'process raft request' (duration: 286.343128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:22:07.078195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.843155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:22:07.078228Z","caller":"traceutil/trace.go:171","msg":"trace[599614081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3536; }","duration":"212.905927ms","start":"2026-03-20T10:22:06.865316Z","end":"2026-03-20T10:22:07.078222Z","steps":["trace[599614081] 'agreement among raft nodes before linearized reading' (duration: 212.837485ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:05.360028Z","caller":"traceutil/trace.go:171","msg":"trace[1473699933] transaction","detail":"{read_only:false; response_revision:4341; number_of_response:1; }","duration":"146.282164ms","start":"2026-03-20T10:23:05.213721Z","end":"2026-03-20T10:23:05.360003Z","steps":["trace[1473699933] 'process raft request' (duration: 146.103529ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:05.360518Z","caller":"traceutil/trace.go:171","msg":"trace[1931344965] linearizableReadLoop","detail":"{readStateIndex:4521; appliedIndex:4521; }","duration":"146.651472ms","start":"2026-03-20T10:23:05.213846Z","end":"2026-03-20T10:23:05.360497Z","steps":["trace[1931344965] 'read index received' (duration: 146.641822ms)","trace[1931344965] 'applied index is now lower than readState.Index' (duration: 8.13µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:05.360727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.858917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-03-20T10:23:05.360809Z","caller":"traceutil/trace.go:171","msg":"trace[1273894899] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4341; }","duration":"146.962869ms","start":"2026-03-20T10:23:05.213828Z","end":"2026-03-20T10:23:05.360791Z","steps":["trace[1273894899] 'agreement among raft nodes before linearized reading' (duration: 146.777745ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:05.37273Z","caller":"traceutil/trace.go:171","msg":"trace[569973311] transaction","detail":"{read_only:false; response_revision:4342; number_of_response:1; }","duration":"120.129161ms","start":"2026-03-20T10:23:05.252551Z","end":"2026-03-20T10:23:05.37268Z","steps":["trace[569973311] 'process raft request' (duration: 119.898496ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:08.115643Z","caller":"traceutil/trace.go:171","msg":"trace[1673657905] linearizableReadLoop","detail":"{readStateIndex:4579; appliedIndex:4578; }","duration":"401.869571ms","start":"2026-03-20T10:23:07.71375Z","end":"2026-03-20T10:23:08.11562Z","steps":["trace[1673657905] 'read index received' (duration: 401.185474ms)","trace[1673657905] 'applied index is now lower than readState.Index' (duration: 682.867µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:08.115803Z","caller":"traceutil/trace.go:171","msg":"trace[1961723267] transaction","detail":"{read_only:false; response_revision:4395; number_of_response:1; }","duration":"402.727821ms","start":"2026-03-20T10:23:07.713034Z","end":"2026-03-20T10:23:08.115762Z","steps":["trace[1961723267] 'process raft request' (duration: 402.164728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:08.115847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.066795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/loki-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:23:08.115926Z","caller":"traceutil/trace.go:171","msg":"trace[416861400] range","detail":"{range_begin:/registry/services/specs/monitoring/loki-gateway; range_end:; response_count:0; response_revision:4395; }","duration":"402.174937ms","start":"2026-03-20T10:23:07.713735Z","end":"2026-03-20T10:23:08.11591Z","steps":["trace[416861400] 'agreement among raft nodes before linearized reading' (duration: 402.016464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:08.115965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:07.713019Z","time spent":"402.867044ms","remote":"127.0.0.1:39538","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-03-20T10:23:08.115984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:07.713728Z","time spent":"402.242969ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/monitoring/loki-gateway\" "} {"level":"warn","ts":"2026-03-20T10:23:08.125939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.998909ms","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-03-20T10:23:08.126Z","caller":"traceutil/trace.go:171","msg":"trace[2029335417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4396; }","duration":"325.089513ms","start":"2026-03-20T10:23:07.800898Z","end":"2026-03-20T10:23:08.125988Z","steps":["trace[2029335417] 'agreement among raft nodes before linearized reading' (duration: 324.99019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:08.126031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:07.800886Z","time spent":"325.136383ms","remote":"127.0.0.1:39538","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-03-20T10:23:08.126134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.066097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-m4hqc\" ","response":"range_response_count:1 size:5148"} {"level":"info","ts":"2026-03-20T10:23:08.126159Z","caller":"traceutil/trace.go:171","msg":"trace[1929156503] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-m4hqc; range_end:; response_count:1; response_revision:4396; }","duration":"400.113167ms","start":"2026-03-20T10:23:07.726039Z","end":"2026-03-20T10:23:08.126152Z","steps":["trace[1929156503] 'agreement among raft nodes before linearized reading' (duration: 400.045616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:08.126175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:07.726023Z","time spent":"400.146409ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":5171,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-m4hqc\" "} {"level":"info","ts":"2026-03-20T10:23:08.35863Z","caller":"traceutil/trace.go:171","msg":"trace[1115329310] transaction","detail":"{read_only:false; response_revision:4400; number_of_response:1; }","duration":"142.127565ms","start":"2026-03-20T10:23:08.216466Z","end":"2026-03-20T10:23:08.358594Z","steps":["trace[1115329310] 'process raft request' (duration: 141.751035ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:08.358776Z","caller":"traceutil/trace.go:171","msg":"trace[1838941530] transaction","detail":"{read_only:false; response_revision:4402; number_of_response:1; }","duration":"117.669202ms","start":"2026-03-20T10:23:08.241072Z","end":"2026-03-20T10:23:08.358741Z","steps":["trace[1838941530] 'process raft request' (duration: 117.465977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:08.358846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.855227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0f6ffe7b-cfb9-4411-9220-54f2c68ca382\" ","response":"range_response_count:1 size:4080"} {"level":"info","ts":"2026-03-20T10:23:08.358938Z","caller":"traceutil/trace.go:171","msg":"trace[516746502] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0f6ffe7b-cfb9-4411-9220-54f2c68ca382; range_end:; response_count:1; response_revision:4402; }","duration":"138.964619ms","start":"2026-03-20T10:23:08.21995Z","end":"2026-03-20T10:23:08.358915Z","steps":["trace[516746502] 'agreement among raft nodes before linearized reading' (duration: 138.721654ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:08.358994Z","caller":"traceutil/trace.go:171","msg":"trace[2037344492] transaction","detail":"{read_only:false; response_revision:4401; number_of_response:1; }","duration":"141.49052ms","start":"2026-03-20T10:23:08.217485Z","end":"2026-03-20T10:23:08.358975Z","steps":["trace[2037344492] 'process raft request' (duration: 140.961507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:08.359128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.865135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-flgtl\" ","response":"range_response_count:1 size:9716"} {"level":"info","ts":"2026-03-20T10:23:08.359184Z","caller":"traceutil/trace.go:171","msg":"trace[1677067936] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-flgtl; range_end:; response_count:1; response_revision:4402; }","duration":"119.966097ms","start":"2026-03-20T10:23:08.239203Z","end":"2026-03-20T10:23:08.359169Z","steps":["trace[1677067936] 'agreement among raft nodes before linearized reading' (duration: 119.725272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:08.359177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.063292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T10:23:08.359281Z","caller":"traceutil/trace.go:171","msg":"trace[473122118] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:4402; }","duration":"126.230616ms","start":"2026-03-20T10:23:08.233025Z","end":"2026-03-20T10:23:08.359255Z","steps":["trace[473122118] 'agreement among raft nodes before linearized reading' (duration: 126.000771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:08.769001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.518514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:116259"} {"level":"info","ts":"2026-03-20T10:23:08.769195Z","caller":"traceutil/trace.go:171","msg":"trace[1187644101] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4426; }","duration":"100.761379ms","start":"2026-03-20T10:23:08.668418Z","end":"2026-03-20T10:23:08.769179Z","steps":["trace[1187644101] 'agreement among raft nodes before linearized reading' (duration: 100.203416ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:09.558294Z","caller":"traceutil/trace.go:171","msg":"trace[1946009485] transaction","detail":"{read_only:false; response_revision:4500; number_of_response:1; }","duration":"189.407601ms","start":"2026-03-20T10:23:09.368861Z","end":"2026-03-20T10:23:09.558269Z","steps":["trace[1946009485] 'process raft request' (duration: 189.225436ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:09.561515Z","caller":"traceutil/trace.go:171","msg":"trace[167068338] transaction","detail":"{read_only:false; response_revision:4501; number_of_response:1; }","duration":"172.767084ms","start":"2026-03-20T10:23:09.38873Z","end":"2026-03-20T10:23:09.561497Z","steps":["trace[167068338] 'process raft request' (duration: 172.634571ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:11.41008Z","caller":"traceutil/trace.go:171","msg":"trace[1045132987] linearizableReadLoop","detail":"{readStateIndex:4735; appliedIndex:4734; }","duration":"241.695865ms","start":"2026-03-20T10:23:11.168365Z","end":"2026-03-20T10:23:11.41006Z","steps":["trace[1045132987] 'read index received' (duration: 241.239434ms)","trace[1045132987] 'applied index is now lower than readState.Index' (duration: 455.331µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:11.410141Z","caller":"traceutil/trace.go:171","msg":"trace[1610856818] transaction","detail":"{read_only:false; response_revision:4547; number_of_response:1; }","duration":"360.093786ms","start":"2026-03-20T10:23:11.049999Z","end":"2026-03-20T10:23:11.410093Z","steps":["trace[1610856818] 'process raft request' (duration: 359.689796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:11.410289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:23:11.049977Z","time spent":"360.240049ms","remote":"127.0.0.1:39348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58198,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T10:23:11.410305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.283027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d31898f4-903c-47b0-b868-d68050ea1005\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-20T10:23:11.410467Z","caller":"traceutil/trace.go:171","msg":"trace[526631765] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d31898f4-903c-47b0-b868-d68050ea1005; range_end:; response_count:1; response_revision:4547; }","duration":"134.489542ms","start":"2026-03-20T10:23:11.275969Z","end":"2026-03-20T10:23:11.410459Z","steps":["trace[526631765] 'agreement among raft nodes before linearized reading' (duration: 134.220766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:11.410538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.137706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d31898f4-903c-47b0-b868-d68050ea1005\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-20T10:23:11.410644Z","caller":"traceutil/trace.go:171","msg":"trace[914388085] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d31898f4-903c-47b0-b868-d68050ea1005; range_end:; response_count:1; response_revision:4547; }","duration":"242.305039ms","start":"2026-03-20T10:23:11.168315Z","end":"2026-03-20T10:23:11.41062Z","steps":["trace[914388085] 'agreement among raft nodes before linearized reading' (duration: 241.862469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:13.841031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.603377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:23:13.841141Z","caller":"traceutil/trace.go:171","msg":"trace[672227886] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"266.908057ms","start":"2026-03-20T10:23:13.574216Z","end":"2026-03-20T10:23:13.841124Z","steps":["trace[672227886] 'process raft request' (duration: 144.893581ms)","trace[672227886] 'compare' (duration: 121.464152ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:13.865558Z","caller":"traceutil/trace.go:171","msg":"trace[1048406958] transaction","detail":"{read_only:false; response_revision:4589; number_of_response:1; }","duration":"289.648677ms","start":"2026-03-20T10:23:13.575865Z","end":"2026-03-20T10:23:13.865514Z","steps":["trace[1048406958] 'process raft request' (duration: 289.422852ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:13.865433Z","caller":"traceutil/trace.go:171","msg":"trace[679826946] linearizableReadLoop","detail":"{readStateIndex:4779; appliedIndex:4777; }","duration":"284.010622ms","start":"2026-03-20T10:23:13.581405Z","end":"2026-03-20T10:23:13.865416Z","steps":["trace[679826946] 'read index received' (duration: 137.77197ms)","trace[679826946] 'applied index is now lower than readState.Index' (duration: 146.237722ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:13.865922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.492594ms","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-03-20T10:23:13.866031Z","caller":"traceutil/trace.go:171","msg":"trace[1275027761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4589; }","duration":"284.674279ms","start":"2026-03-20T10:23:13.581332Z","end":"2026-03-20T10:23:13.866007Z","steps":["trace[1275027761] 'agreement among raft nodes before linearized reading' (duration: 284.3482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:13.875503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.856104ms","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-03-20T10:23:13.875572Z","caller":"traceutil/trace.go:171","msg":"trace[247801180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4590; }","duration":"292.976737ms","start":"2026-03-20T10:23:13.582579Z","end":"2026-03-20T10:23:13.875556Z","steps":["trace[247801180] 'agreement among raft nodes before linearized reading' (duration: 292.785943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:13.8759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.475358ms","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-03-20T10:23:13.875933Z","caller":"traceutil/trace.go:171","msg":"trace[1395313195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4590; }","duration":"290.530909ms","start":"2026-03-20T10:23:13.585395Z","end":"2026-03-20T10:23:13.875926Z","steps":["trace[1395313195] 'agreement among raft nodes before linearized reading' (duration: 290.445337ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:21.244369Z","caller":"traceutil/trace.go:171","msg":"trace[1521164216] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4721; }","duration":"203.453204ms","start":"2026-03-20T10:23:21.040876Z","end":"2026-03-20T10:23:21.244329Z","steps":["trace[1521164216] 'process raft request' (duration: 203.339411ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:21.447534Z","caller":"traceutil/trace.go:171","msg":"trace[986660854] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"195.703091ms","start":"2026-03-20T10:23:21.251811Z","end":"2026-03-20T10:23:21.447514Z","steps":["trace[986660854] 'process raft request' (duration: 195.479576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:21.749718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.334303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:23:21.749969Z","caller":"traceutil/trace.go:171","msg":"trace[371759484] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"291.248346ms","start":"2026-03-20T10:23:21.458699Z","end":"2026-03-20T10:23:21.749947Z","steps":["trace[371759484] 'process raft request' (duration: 291.131543ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:21.750235Z","caller":"traceutil/trace.go:171","msg":"trace[365897465] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"298.15759ms","start":"2026-03-20T10:23:21.452053Z","end":"2026-03-20T10:23:21.75021Z","steps":["trace[365897465] 'process raft request' (duration: 70.243323ms)","trace[365897465] 'compare' (duration: 227.161349ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:21.965214Z","caller":"traceutil/trace.go:171","msg":"trace[318056203] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"209.837136ms","start":"2026-03-20T10:23:21.755356Z","end":"2026-03-20T10:23:21.965193Z","steps":["trace[318056203] 'process raft request' (duration: 209.758495ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:21.965246Z","caller":"traceutil/trace.go:171","msg":"trace[2029986494] linearizableReadLoop","detail":"{readStateIndex:4923; appliedIndex:4919; }","duration":"166.72116ms","start":"2026-03-20T10:23:21.798505Z","end":"2026-03-20T10:23:21.965226Z","steps":["trace[2029986494] 'read index received' (duration: 93.031455ms)","trace[2029986494] 'applied index is now lower than readState.Index' (duration: 73.687765ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:21.965274Z","caller":"traceutil/trace.go:171","msg":"trace[225742358] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"208.492125ms","start":"2026-03-20T10:23:21.756772Z","end":"2026-03-20T10:23:21.965264Z","steps":["trace[225742358] 'process raft request' (duration: 208.417913ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:21.965205Z","caller":"traceutil/trace.go:171","msg":"trace[1704465142] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"209.922399ms","start":"2026-03-20T10:23:21.755248Z","end":"2026-03-20T10:23:21.96517Z","steps":["trace[1704465142] 'process raft request' (duration: 136.332696ms)","trace[1704465142] 'compare' (duration: 73.412129ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:21.965265Z","caller":"traceutil/trace.go:171","msg":"trace[1810871563] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"209.13072ms","start":"2026-03-20T10:23:21.756105Z","end":"2026-03-20T10:23:21.965236Z","steps":["trace[1810871563] 'process raft request' (duration: 209.047328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:21.965482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.958927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:116259"} {"level":"info","ts":"2026-03-20T10:23:21.965514Z","caller":"traceutil/trace.go:171","msg":"trace[686998513] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4729; }","duration":"167.042818ms","start":"2026-03-20T10:23:21.798463Z","end":"2026-03-20T10:23:21.965506Z","steps":["trace[686998513] 'agreement among raft nodes before linearized reading' (duration: 166.866824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:22.205528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.549293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:23:22.205772Z","caller":"traceutil/trace.go:171","msg":"trace[2092508257] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"235.873488ms","start":"2026-03-20T10:23:21.969882Z","end":"2026-03-20T10:23:22.205755Z","steps":["trace[2092508257] 'process raft request' (duration: 235.816667ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:22.205804Z","caller":"traceutil/trace.go:171","msg":"trace[778829415] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"235.96222ms","start":"2026-03-20T10:23:21.969824Z","end":"2026-03-20T10:23:22.205787Z","steps":["trace[778829415] 'process raft request' (duration: 235.851127ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:22.205814Z","caller":"traceutil/trace.go:171","msg":"trace[672492003] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"238.239073ms","start":"2026-03-20T10:23:21.96755Z","end":"2026-03-20T10:23:22.205789Z","steps":["trace[672492003] 'process raft request' (duration: 106.350731ms)","trace[672492003] 'compare' (duration: 131.42154ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:22.205774Z","caller":"traceutil/trace.go:171","msg":"trace[18686998] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"236.001841ms","start":"2026-03-20T10:23:21.969755Z","end":"2026-03-20T10:23:22.205756Z","steps":["trace[18686998] 'process raft request' (duration: 235.872988ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:22.205938Z","caller":"traceutil/trace.go:171","msg":"trace[2048313744] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"171.466624ms","start":"2026-03-20T10:23:22.034452Z","end":"2026-03-20T10:23:22.205919Z","steps":["trace[2048313744] 'process raft request' (duration: 171.267189ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:22.46835Z","caller":"traceutil/trace.go:171","msg":"trace[1148723928] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"249.241395ms","start":"2026-03-20T10:23:22.219075Z","end":"2026-03-20T10:23:22.468316Z","steps":["trace[1148723928] 'process raft request' (duration: 201.690682ms)","trace[1148723928] 'compare' (duration: 47.42961ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:22.48731Z","caller":"traceutil/trace.go:171","msg":"trace[589347988] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"219.130988ms","start":"2026-03-20T10:23:22.268139Z","end":"2026-03-20T10:23:22.48727Z","steps":["trace[589347988] 'process raft request' (duration: 218.975535ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:22.487352Z","caller":"traceutil/trace.go:171","msg":"trace[782866578] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"218.79898ms","start":"2026-03-20T10:23:22.268525Z","end":"2026-03-20T10:23:22.487324Z","steps":["trace[782866578] 'process raft request' (duration: 218.621926ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:22.487489Z","caller":"traceutil/trace.go:171","msg":"trace[931037617] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"215.827569ms","start":"2026-03-20T10:23:22.271647Z","end":"2026-03-20T10:23:22.487475Z","steps":["trace[931037617] 'process raft request' (duration: 215.531272ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:22.487792Z","caller":"traceutil/trace.go:171","msg":"trace[2025467598] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"219.6781ms","start":"2026-03-20T10:23:22.268108Z","end":"2026-03-20T10:23:22.487786Z","steps":["trace[2025467598] 'process raft request' (duration: 218.949093ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:23.505343Z","caller":"traceutil/trace.go:171","msg":"trace[1266373969] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"113.289577ms","start":"2026-03-20T10:23:23.39201Z","end":"2026-03-20T10:23:23.5053Z","steps":["trace[1266373969] 'process raft request' (duration: 76.42952ms)","trace[1266373969] 'compare' (duration: 36.699103ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:23.505792Z","caller":"traceutil/trace.go:171","msg":"trace[1801508387] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"109.517427ms","start":"2026-03-20T10:23:23.396258Z","end":"2026-03-20T10:23:23.505775Z","steps":["trace[1801508387] 'process raft request' (duration: 109.384624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:31.605816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.454501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-chunks-cache-0\" ","response":"range_response_count:1 size:4464"} {"level":"info","ts":"2026-03-20T10:23:31.60593Z","caller":"traceutil/trace.go:171","msg":"trace[640844301] range","detail":"{range_begin:/registry/pods/monitoring/loki-chunks-cache-0; range_end:; response_count:1; response_revision:4913; }","duration":"155.623255ms","start":"2026-03-20T10:23:31.450281Z","end":"2026-03-20T10:23:31.605904Z","steps":["trace[640844301] 'range keys from in-memory index tree' (duration: 155.198616ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:31.73631Z","caller":"traceutil/trace.go:171","msg":"trace[61869826] linearizableReadLoop","detail":"{readStateIndex:5110; appliedIndex:5109; }","duration":"104.287044ms","start":"2026-03-20T10:23:31.632003Z","end":"2026-03-20T10:23:31.73629Z","steps":["trace[61869826] 'read index received' (duration: 104.201232ms)","trace[61869826] 'applied index is now lower than readState.Index' (duration: 85.082µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:31.736396Z","caller":"traceutil/trace.go:171","msg":"trace[1352515661] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"123.219924ms","start":"2026-03-20T10:23:31.613162Z","end":"2026-03-20T10:23:31.736382Z","steps":["trace[1352515661] 'process raft request' (duration: 123.001089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:31.736477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.455818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0ddc701b-cbb9-4b4e-ad8c-9eaa7c56fe73\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-20T10:23:31.736502Z","caller":"traceutil/trace.go:171","msg":"trace[1499224266] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0ddc701b-cbb9-4b4e-ad8c-9eaa7c56fe73; range_end:; response_count:1; response_revision:4914; }","duration":"104.51739ms","start":"2026-03-20T10:23:31.631978Z","end":"2026-03-20T10:23:31.736496Z","steps":["trace[1499224266] 'agreement among raft nodes before linearized reading' (duration: 104.387377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:31.961469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.037539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:23:31.961736Z","caller":"traceutil/trace.go:171","msg":"trace[1204350944] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"217.238662ms","start":"2026-03-20T10:23:31.744465Z","end":"2026-03-20T10:23:31.961704Z","steps":["trace[1204350944] 'process raft request' (duration: 96.899557ms)","trace[1204350944] 'compare' (duration: 119.808933ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:31.961772Z","caller":"traceutil/trace.go:171","msg":"trace[1979667384] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"217.272963ms","start":"2026-03-20T10:23:31.744482Z","end":"2026-03-20T10:23:31.961755Z","steps":["trace[1979667384] 'process raft request' (duration: 217.10522ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:31.962089Z","caller":"traceutil/trace.go:171","msg":"trace[1312770944] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"217.588671ms","start":"2026-03-20T10:23:31.744483Z","end":"2026-03-20T10:23:31.962071Z","steps":["trace[1312770944] 'process raft request' (duration: 217.215622ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:31.962215Z","caller":"traceutil/trace.go:171","msg":"trace[42286323] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"216.962196ms","start":"2026-03-20T10:23:31.745246Z","end":"2026-03-20T10:23:31.962208Z","steps":["trace[42286323] 'process raft request' (duration: 216.831383ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:31.962203Z","caller":"traceutil/trace.go:171","msg":"trace[131914328] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"217.601911ms","start":"2026-03-20T10:23:31.744575Z","end":"2026-03-20T10:23:31.962177Z","steps":["trace[131914328] 'process raft request' (duration: 217.393647ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:31.962831Z","caller":"traceutil/trace.go:171","msg":"trace[1652633030] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"216.829144ms","start":"2026-03-20T10:23:31.745975Z","end":"2026-03-20T10:23:31.962804Z","steps":["trace[1652633030] 'process raft request' (duration: 216.169108ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:31.963466Z","caller":"traceutil/trace.go:171","msg":"trace[1731966568] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"216.630749ms","start":"2026-03-20T10:23:31.746819Z","end":"2026-03-20T10:23:31.96345Z","steps":["trace[1731966568] 'process raft request' (duration: 215.85804ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:33.640535Z","caller":"traceutil/trace.go:171","msg":"trace[1162304470] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"139.198674ms","start":"2026-03-20T10:23:33.50128Z","end":"2026-03-20T10:23:33.640479Z","steps":["trace[1162304470] 'process raft request' (duration: 122.875746ms)","trace[1162304470] 'compare' (duration: 15.482188ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:33.640794Z","caller":"traceutil/trace.go:171","msg":"trace[39136515] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"139.473451ms","start":"2026-03-20T10:23:33.501303Z","end":"2026-03-20T10:23:33.640776Z","steps":["trace[39136515] 'process raft request' (duration: 139.104922ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:33.640934Z","caller":"traceutil/trace.go:171","msg":"trace[507992910] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"139.424891ms","start":"2026-03-20T10:23:33.501479Z","end":"2026-03-20T10:23:33.640904Z","steps":["trace[507992910] 'process raft request' (duration: 139.220575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:34.755642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.229685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:23:34.755829Z","caller":"traceutil/trace.go:171","msg":"trace[879151873] linearizableReadLoop","detail":"{readStateIndex:5165; appliedIndex:5164; }","duration":"203.595479ms","start":"2026-03-20T10:23:34.552212Z","end":"2026-03-20T10:23:34.755808Z","steps":["trace[879151873] 'read index received' (duration: 77.040425ms)","trace[879151873] 'applied index is now lower than readState.Index' (duration: 126.549894ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:34.755892Z","caller":"traceutil/trace.go:171","msg":"trace[497923787] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"203.980397ms","start":"2026-03-20T10:23:34.551878Z","end":"2026-03-20T10:23:34.755859Z","steps":["trace[497923787] 'process raft request' (duration: 77.430163ms)","trace[497923787] 'compare' (duration: 126.100312ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:34.756013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.789323ms","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-03-20T10:23:34.756073Z","caller":"traceutil/trace.go:171","msg":"trace[1686404524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4968; }","duration":"203.856865ms","start":"2026-03-20T10:23:34.5522Z","end":"2026-03-20T10:23:34.756057Z","steps":["trace[1686404524] 'agreement among raft nodes before linearized reading' (duration: 203.694851ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:34.757688Z","caller":"traceutil/trace.go:171","msg":"trace[1675354712] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"119.167237ms","start":"2026-03-20T10:23:34.638486Z","end":"2026-03-20T10:23:34.757653Z","steps":["trace[1675354712] 'process raft request' (duration: 118.833659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:44.041629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.373197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:23:44.041736Z","caller":"traceutil/trace.go:171","msg":"trace[2060640556] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:5088; }","duration":"102.555442ms","start":"2026-03-20T10:23:43.939155Z","end":"2026-03-20T10:23:44.041711Z","steps":["trace[2060640556] 'count revisions from in-memory index tree' (duration: 102.207913ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:45.724371Z","caller":"traceutil/trace.go:171","msg":"trace[1972629984] linearizableReadLoop","detail":"{readStateIndex:5308; appliedIndex:5307; }","duration":"112.316375ms","start":"2026-03-20T10:23:45.612033Z","end":"2026-03-20T10:23:45.724349Z","steps":["trace[1972629984] 'read index received' (duration: 112.11329ms)","trace[1972629984] 'applied index is now lower than readState.Index' (duration: 202.215µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:45.724451Z","caller":"traceutil/trace.go:171","msg":"trace[1532819200] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"116.450794ms","start":"2026-03-20T10:23:45.607964Z","end":"2026-03-20T10:23:45.724415Z","steps":["trace[1532819200] 'process raft request' (duration: 116.240089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:23:45.724662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.599742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-03-20T10:23:45.724766Z","caller":"traceutil/trace.go:171","msg":"trace[1367955661] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5106; }","duration":"112.738204ms","start":"2026-03-20T10:23:45.612Z","end":"2026-03-20T10:23:45.724738Z","steps":["trace[1367955661] 'agreement among raft nodes before linearized reading' (duration: 112.454978ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:45.862863Z","caller":"traceutil/trace.go:171","msg":"trace[860005100] linearizableReadLoop","detail":"{readStateIndex:5309; appliedIndex:5308; }","duration":"119.830123ms","start":"2026-03-20T10:23:45.74301Z","end":"2026-03-20T10:23:45.86284Z","steps":["trace[860005100] 'read index received' (duration: 88.027366ms)","trace[860005100] 'applied index is now lower than readState.Index' (duration: 31.801927ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:23:45.863065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.039207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-03-20T10:23:45.862988Z","caller":"traceutil/trace.go:171","msg":"trace[369252409] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"122.396624ms","start":"2026-03-20T10:23:45.740536Z","end":"2026-03-20T10:23:45.862933Z","steps":["trace[369252409] 'process raft request' (duration: 90.580016ms)","trace[369252409] 'compare' (duration: 31.603643ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:23:45.863108Z","caller":"traceutil/trace.go:171","msg":"trace[832074353] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5107; }","duration":"120.13884ms","start":"2026-03-20T10:23:45.742957Z","end":"2026-03-20T10:23:45.863096Z","steps":["trace[832074353] 'agreement among raft nodes before linearized reading' (duration: 119.972306ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:25:04.176038Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2744} {"level":"info","ts":"2026-03-20T10:25:04.260581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2744,"took":"83.022744ms","hash":2729705314,"current-db-size-bytes":41177088,"current-db-size":"41 MB","current-db-size-in-use-bytes":30781440,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-20T10:25:04.260726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2729705314,"revision":2744,"compact-revision":-1} {"level":"info","ts":"2026-03-20T10:28:07.039128Z","caller":"traceutil/trace.go:171","msg":"trace[1761059235] transaction","detail":"{read_only:false; response_revision:6862; number_of_response:1; }","duration":"116.985276ms","start":"2026-03-20T10:28:06.922104Z","end":"2026-03-20T10:28:07.039089Z","steps":["trace[1761059235] 'process raft request' (duration: 95.707887ms)","trace[1761059235] 'compare' (duration: 21.093055ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:28:20.803537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.749197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l7vxp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-20T10:28:20.803642Z","caller":"traceutil/trace.go:171","msg":"trace[331166578] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l7vxp; range_end:; response_count:1; response_revision:6946; }","duration":"141.911831ms","start":"2026-03-20T10:28:20.66171Z","end":"2026-03-20T10:28:20.803621Z","steps":["trace[331166578] 'range keys from in-memory index tree' (duration: 141.573573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:20.803859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.312978ms","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-03-20T10:28:20.803988Z","caller":"traceutil/trace.go:171","msg":"trace[739695886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6946; }","duration":"198.515153ms","start":"2026-03-20T10:28:20.605445Z","end":"2026-03-20T10:28:20.80396Z","steps":["trace[739695886] 'range keys from in-memory index tree' (duration: 198.058842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:21.802279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.814992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:28:21.802431Z","caller":"traceutil/trace.go:171","msg":"trace[1824905983] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:6948; }","duration":"270.977596ms","start":"2026-03-20T10:28:21.531393Z","end":"2026-03-20T10:28:21.802371Z","steps":["trace[1824905983] 'range keys from in-memory index tree' (duration: 270.719821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:21.802685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.643686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-db-sync\" ","response":"range_response_count:1 size:5488"} {"level":"info","ts":"2026-03-20T10:28:21.802752Z","caller":"traceutil/trace.go:171","msg":"trace[1744630397] range","detail":"{range_begin:/registry/jobs/openstack/barbican-db-sync; range_end:; response_count:1; response_revision:6948; }","duration":"259.738928ms","start":"2026-03-20T10:28:21.542996Z","end":"2026-03-20T10:28:21.802735Z","steps":["trace[1744630397] 'range keys from in-memory index tree' (duration: 259.490982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:21.802734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.077069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:28:21.8028Z","caller":"traceutil/trace.go:171","msg":"trace[1925084837] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:0; response_revision:6948; }","duration":"271.16241ms","start":"2026-03-20T10:28:21.531623Z","end":"2026-03-20T10:28:21.802785Z","steps":["trace[1925084837] 'range keys from in-memory index tree' (duration: 271.000246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:21.802457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.090335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T10:28:21.802845Z","caller":"traceutil/trace.go:171","msg":"trace[1545627370] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:6948; }","duration":"229.604837ms","start":"2026-03-20T10:28:21.573235Z","end":"2026-03-20T10:28:21.80284Z","steps":["trace[1545627370] 'count revisions from in-memory index tree' (duration: 228.999823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:21.802906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.227572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:28:21.80304Z","caller":"traceutil/trace.go:171","msg":"trace[474016004] range","detail":"{range_begin:/registry/jobs/openstack/barbican-rabbit-init; range_end:; response_count:0; response_revision:6948; }","duration":"271.356845ms","start":"2026-03-20T10:28:21.531641Z","end":"2026-03-20T10:28:21.802998Z","steps":["trace[474016004] 'range keys from in-memory index tree' (duration: 271.13657ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:21.817177Z","caller":"traceutil/trace.go:171","msg":"trace[1742189404] transaction","detail":"{read_only:false; response_revision:6949; number_of_response:1; }","duration":"144.609716ms","start":"2026-03-20T10:28:21.672549Z","end":"2026-03-20T10:28:21.817159Z","steps":["trace[1742189404] 'process raft request' (duration: 144.483643ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:59.553835Z","caller":"traceutil/trace.go:171","msg":"trace[1198905094] linearizableReadLoop","detail":"{readStateIndex:7468; appliedIndex:7467; }","duration":"117.705493ms","start":"2026-03-20T10:28:59.436078Z","end":"2026-03-20T10:28:59.553784Z","steps":["trace[1198905094] 'read index received' (duration: 32.425854ms)","trace[1198905094] 'applied index is now lower than readState.Index' (duration: 85.278229ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:28:59.553955Z","caller":"traceutil/trace.go:171","msg":"trace[1107786567] transaction","detail":"{read_only:false; response_revision:7192; number_of_response:1; }","duration":"122.975209ms","start":"2026-03-20T10:28:59.430938Z","end":"2026-03-20T10:28:59.553913Z","steps":["trace[1107786567] 'process raft request' (duration: 37.567277ms)","trace[1107786567] 'compare' (duration: 84.759366ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:28:59.554241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.125862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/barbican-ks-endpoints-q7vg8\" ","response":"range_response_count:1 size:13111"} {"level":"info","ts":"2026-03-20T10:28:59.55436Z","caller":"traceutil/trace.go:171","msg":"trace[1616655494] range","detail":"{range_begin:/registry/pods/openstack/barbican-ks-endpoints-q7vg8; range_end:; response_count:1; response_revision:7192; }","duration":"118.283387ms","start":"2026-03-20T10:28:59.43605Z","end":"2026-03-20T10:28:59.554333Z","steps":["trace[1616655494] 'agreement among raft nodes before linearized reading' (duration: 117.869897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:02.76961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.452321ms","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-03-20T10:29:02.769697Z","caller":"traceutil/trace.go:171","msg":"trace[2024209604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7216; }","duration":"105.617204ms","start":"2026-03-20T10:29:02.664063Z","end":"2026-03-20T10:29:02.76968Z","steps":["trace[2024209604] 'range keys from in-memory index tree' (duration: 105.284197ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:03.290416Z","caller":"traceutil/trace.go:171","msg":"trace[921059129] transaction","detail":"{read_only:false; response_revision:7219; number_of_response:1; }","duration":"208.219497ms","start":"2026-03-20T10:29:03.082148Z","end":"2026-03-20T10:29:03.290367Z","steps":["trace[921059129] 'process raft request' (duration: 148.53081ms)","trace[921059129] 'compare' (duration: 59.489522ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:03.29032Z","caller":"traceutil/trace.go:171","msg":"trace[1476538075] linearizableReadLoop","detail":"{readStateIndex:7496; appliedIndex:7494; }","duration":"193.458433ms","start":"2026-03-20T10:29:03.096838Z","end":"2026-03-20T10:29:03.290297Z","steps":["trace[1476538075] 'read index received' (duration: 46.993742ms)","trace[1476538075] 'applied index is now lower than readState.Index' (duration: 146.463321ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:29:03.290752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.893203ms","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-03-20T10:29:03.290846Z","caller":"traceutil/trace.go:171","msg":"trace[1643890983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7219; }","duration":"194.033707ms","start":"2026-03-20T10:29:03.09679Z","end":"2026-03-20T10:29:03.290824Z","steps":["trace[1643890983] 'agreement among raft nodes before linearized reading' (duration: 193.813061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:03.291189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.594067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l7vxp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-20T10:29:03.291303Z","caller":"traceutil/trace.go:171","msg":"trace[1453363585] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l7vxp; range_end:; response_count:1; response_revision:7219; }","duration":"175.75937ms","start":"2026-03-20T10:29:03.115517Z","end":"2026-03-20T10:29:03.291276Z","steps":["trace[1453363585] 'agreement among raft nodes before linearized reading' (duration: 175.402552ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:04.215134Z","caller":"traceutil/trace.go:171","msg":"trace[2023832127] transaction","detail":"{read_only:false; response_revision:7225; number_of_response:1; }","duration":"114.428854ms","start":"2026-03-20T10:29:04.100632Z","end":"2026-03-20T10:29:04.215061Z","steps":["trace[2023832127] 'process raft request' (duration: 74.697235ms)","trace[2023832127] 'compare' (duration: 39.548755ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:04.675663Z","caller":"traceutil/trace.go:171","msg":"trace[557981890] linearizableReadLoop","detail":"{readStateIndex:7507; appliedIndex:7503; }","duration":"140.252142ms","start":"2026-03-20T10:29:04.535395Z","end":"2026-03-20T10:29:04.675647Z","steps":["trace[557981890] 'read index received' (duration: 80.565176ms)","trace[557981890] 'applied index is now lower than readState.Index' (duration: 59.686076ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:29:04.675828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.419126ms","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-03-20T10:29:04.675853Z","caller":"traceutil/trace.go:171","msg":"trace[1384600171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7230; }","duration":"140.494958ms","start":"2026-03-20T10:29:04.535351Z","end":"2026-03-20T10:29:04.675846Z","steps":["trace[1384600171] 'agreement among raft nodes before linearized reading' (duration: 140.363425ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:04.676049Z","caller":"traceutil/trace.go:171","msg":"trace[46757965] transaction","detail":"{read_only:false; response_revision:7227; number_of_response:1; }","duration":"220.734145ms","start":"2026-03-20T10:29:04.455306Z","end":"2026-03-20T10:29:04.67604Z","steps":["trace[46757965] 'process raft request' (duration: 160.761592ms)","trace[46757965] 'compare' (duration: 59.38727ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:29:04.676183Z","caller":"traceutil/trace.go:171","msg":"trace[668636467] transaction","detail":"{read_only:false; response_revision:7228; number_of_response:1; }","duration":"218.976893ms","start":"2026-03-20T10:29:04.4572Z","end":"2026-03-20T10:29:04.676177Z","steps":["trace[668636467] 'process raft request' (duration: 218.337078ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:04.676271Z","caller":"traceutil/trace.go:171","msg":"trace[1987992344] transaction","detail":"{read_only:false; response_revision:7229; number_of_response:1; }","duration":"203.78648ms","start":"2026-03-20T10:29:04.472479Z","end":"2026-03-20T10:29:04.676266Z","steps":["trace[1987992344] 'process raft request' (duration: 203.110714ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:04.676344Z","caller":"traceutil/trace.go:171","msg":"trace[2085362155] transaction","detail":"{read_only:false; response_revision:7230; number_of_response:1; }","duration":"203.669416ms","start":"2026-03-20T10:29:04.47267Z","end":"2026-03-20T10:29:04.676339Z","steps":["trace[2085362155] 'process raft request' (duration: 202.947789ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:04.918146Z","caller":"traceutil/trace.go:171","msg":"trace[1566251412] transaction","detail":"{read_only:false; response_revision:7233; number_of_response:1; }","duration":"163.773234ms","start":"2026-03-20T10:29:04.754347Z","end":"2026-03-20T10:29:04.91812Z","steps":["trace[1566251412] 'process raft request' (duration: 162.977974ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:06.332867Z","caller":"traceutil/trace.go:171","msg":"trace[2063423987] transaction","detail":"{read_only:false; response_revision:7240; number_of_response:1; }","duration":"102.92821ms","start":"2026-03-20T10:29:06.229912Z","end":"2026-03-20T10:29:06.33284Z","steps":["trace[2063423987] 'process raft request' (duration: 31.837541ms)","trace[2063423987] 'compare' (duration: 70.906995ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:03.807557Z","caller":"traceutil/trace.go:171","msg":"trace[1936958271] linearizableReadLoop","detail":"{readStateIndex:8011; appliedIndex:8010; }","duration":"118.17079ms","start":"2026-03-20T10:30:03.689364Z","end":"2026-03-20T10:30:03.807535Z","steps":["trace[1936958271] 'read index received' (duration: 117.979085ms)","trace[1936958271] 'applied index is now lower than readState.Index' (duration: 190.805µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:03.807614Z","caller":"traceutil/trace.go:171","msg":"trace[1598192176] transaction","detail":"{read_only:false; response_revision:7719; number_of_response:1; }","duration":"126.891158ms","start":"2026-03-20T10:30:03.680692Z","end":"2026-03-20T10:30:03.807583Z","steps":["trace[1598192176] 'process raft request' (duration: 126.706194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:03.888663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.278835ms","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-03-20T10:30:03.888727Z","caller":"traceutil/trace.go:171","msg":"trace[1346279450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7719; }","duration":"199.384707ms","start":"2026-03-20T10:30:03.689326Z","end":"2026-03-20T10:30:03.888711Z","steps":["trace[1346279450] 'agreement among raft nodes before linearized reading' (duration: 118.298412ms)","trace[1346279450] 'range keys from in-memory index tree' (duration: 80.89868ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:04.302789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5699} {"level":"info","ts":"2026-03-20T10:30:04.520314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5699,"took":"215.85759ms","hash":980080151,"current-db-size-bytes":42737664,"current-db-size":"43 MB","current-db-size-in-use-bytes":23289856,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-20T10:30:04.520397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":980080151,"revision":5699,"compact-revision":2744} {"level":"warn","ts":"2026-03-20T10:30:05.931497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.175669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l7vxp\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-03-20T10:30:05.931277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.353366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123153"} {"level":"info","ts":"2026-03-20T10:30:05.9316Z","caller":"traceutil/trace.go:171","msg":"trace[711251535] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l7vxp; range_end:; response_count:1; response_revision:7728; }","duration":"263.321093ms","start":"2026-03-20T10:30:05.66825Z","end":"2026-03-20T10:30:05.931571Z","steps":["trace[711251535] 'range keys from in-memory index tree' (duration: 262.966163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:05.931604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.586247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-03-20T10:30:05.931642Z","caller":"traceutil/trace.go:171","msg":"trace[1391937122] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:7728; }","duration":"406.743096ms","start":"2026-03-20T10:30:05.524883Z","end":"2026-03-20T10:30:05.931626Z","steps":["trace[1391937122] 'range keys from in-memory index tree' (duration: 406.215592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:05.93167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:05.524876Z","time spent":"406.786287ms","remote":"127.0.0.1:39316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":123176,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-03-20T10:30:05.931686Z","caller":"traceutil/trace.go:171","msg":"trace[550146467] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:7728; }","duration":"410.69386ms","start":"2026-03-20T10:30:05.520968Z","end":"2026-03-20T10:30:05.931661Z","steps":["trace[550146467] 'range keys from in-memory index tree' (duration: 410.426332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:05.931732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:05.520953Z","time spent":"410.766121ms","remote":"127.0.0.1:39332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-03-20T10:30:17.158092Z","caller":"traceutil/trace.go:171","msg":"trace[1598239571] linearizableReadLoop","detail":"{readStateIndex:8075; appliedIndex:8074; }","duration":"120.672459ms","start":"2026-03-20T10:30:17.037389Z","end":"2026-03-20T10:30:17.158062Z","steps":["trace[1598239571] 'read index received' (duration: 85.10298ms)","trace[1598239571] 'applied index is now lower than readState.Index' (duration: 35.567949ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:17.158177Z","caller":"traceutil/trace.go:171","msg":"trace[1530466907] transaction","detail":"{read_only:false; response_revision:7780; number_of_response:1; }","duration":"127.969884ms","start":"2026-03-20T10:30:17.03015Z","end":"2026-03-20T10:30:17.15812Z","steps":["trace[1530466907] 'process raft request' (duration: 92.315563ms)","trace[1530466907] 'compare' (duration: 35.454827ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:30:17.158292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.866294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:30:17.158332Z","caller":"traceutil/trace.go:171","msg":"trace[1901583073] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7780; }","duration":"120.967816ms","start":"2026-03-20T10:30:17.037353Z","end":"2026-03-20T10:30:17.158321Z","steps":["trace[1901583073] 'agreement among raft nodes before linearized reading' (duration: 120.860673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:17.655471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.654646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:30:17.65561Z","caller":"traceutil/trace.go:171","msg":"trace[1198258863] transaction","detail":"{read_only:false; response_revision:7782; number_of_response:1; }","duration":"133.516046ms","start":"2026-03-20T10:30:17.52208Z","end":"2026-03-20T10:30:17.655596Z","steps":["trace[1198258863] 'process raft request' (duration: 133.464045ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:17.655902Z","caller":"traceutil/trace.go:171","msg":"trace[587202444] linearizableReadLoop","detail":"{readStateIndex:8076; appliedIndex:8075; }","duration":"497.684105ms","start":"2026-03-20T10:30:17.158183Z","end":"2026-03-20T10:30:17.655867Z","steps":["trace[587202444] 'read index received' (duration: 105.523468ms)","trace[587202444] 'applied index is now lower than readState.Index' (duration: 392.157177ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:17.656056Z","caller":"traceutil/trace.go:171","msg":"trace[1871430186] transaction","detail":"{read_only:false; response_revision:7781; number_of_response:1; }","duration":"594.31758ms","start":"2026-03-20T10:30:17.06172Z","end":"2026-03-20T10:30:17.656038Z","steps":["trace[1871430186] 'process raft request' (duration: 202.019879ms)","trace[1871430186] 'compare' (duration: 391.557304ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:30:17.656152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.584927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:30:17.65618Z","caller":"traceutil/trace.go:171","msg":"trace[1241813345] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7782; }","duration":"493.647108ms","start":"2026-03-20T10:30:17.162526Z","end":"2026-03-20T10:30:17.656173Z","steps":["trace[1241813345] 'agreement among raft nodes before linearized reading' (duration: 493.600647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:17.656198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:17.162506Z","time spent":"493.68696ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-03-20T10:30:17.65621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:17.06169Z","time spent":"594.414323ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19434,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:30:17.656253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.518274ms","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":"warn","ts":"2026-03-20T10:30:17.656277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.167545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-20T10:30:17.656426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.057041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-03-20T10:30:17.656459Z","caller":"traceutil/trace.go:171","msg":"trace[620535844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7782; }","duration":"521.386231ms","start":"2026-03-20T10:30:17.135054Z","end":"2026-03-20T10:30:17.656441Z","steps":["trace[620535844] 'agreement among raft nodes before linearized reading' (duration: 521.068673ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:17.656508Z","caller":"traceutil/trace.go:171","msg":"trace[264868377] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7782; }","duration":"176.198705ms","start":"2026-03-20T10:30:17.480289Z","end":"2026-03-20T10:30:17.656488Z","steps":["trace[264868377] 'agreement among raft nodes before linearized reading' (duration: 175.938719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:17.65655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:17.135025Z","time spent":"521.508884ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T10:30:17.656258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.791048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-03-20T10:30:17.656685Z","caller":"traceutil/trace.go:171","msg":"trace[1350610200] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:7782; }","duration":"604.279469ms","start":"2026-03-20T10:30:17.052387Z","end":"2026-03-20T10:30:17.656667Z","steps":["trace[1350610200] 'agreement among raft nodes before linearized reading' (duration: 603.717036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:17.656758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:17.052358Z","time spent":"604.381322ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-03-20T10:30:17.656332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.645567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.123\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T10:30:17.657015Z","caller":"traceutil/trace.go:171","msg":"trace[1181817203] range","detail":"{range_begin:/registry/masterleases/199.19.213.123; range_end:; response_count:1; response_revision:7782; }","duration":"333.357354ms","start":"2026-03-20T10:30:17.323642Z","end":"2026-03-20T10:30:17.656999Z","steps":["trace[1181817203] 'agreement among raft nodes before linearized reading' (duration: 332.641317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:17.657068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:17.323617Z","time spent":"333.435566ms","remote":"127.0.0.1:39296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.123\" "} {"level":"info","ts":"2026-03-20T10:30:17.656336Z","caller":"traceutil/trace.go:171","msg":"trace[1553164268] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:7782; }","duration":"249.649367ms","start":"2026-03-20T10:30:17.406667Z","end":"2026-03-20T10:30:17.656317Z","steps":["trace[1553164268] 'agreement among raft nodes before linearized reading' (duration: 249.511004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:17.656358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.567705ms","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-03-20T10:30:17.656358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.81497ms","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-03-20T10:30:17.657455Z","caller":"traceutil/trace.go:171","msg":"trace[667782773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7782; }","duration":"532.641279ms","start":"2026-03-20T10:30:17.124757Z","end":"2026-03-20T10:30:17.657398Z","steps":["trace[667782773] 'agreement among raft nodes before linearized reading' (duration: 531.545743ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:17.657529Z","caller":"traceutil/trace.go:171","msg":"trace[1517249950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7782; }","duration":"269.022609ms","start":"2026-03-20T10:30:17.388488Z","end":"2026-03-20T10:30:17.65751Z","steps":["trace[1517249950] 'agreement among raft nodes before linearized reading' (duration: 267.741799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:17.657591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:17.124742Z","time spent":"532.801364ms","remote":"127.0.0.1:39538","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-03-20T10:30:19.177117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.451018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:30:19.177203Z","caller":"traceutil/trace.go:171","msg":"trace[1604941813] transaction","detail":"{read_only:false; response_revision:7793; number_of_response:1; }","duration":"194.319717ms","start":"2026-03-20T10:30:18.98287Z","end":"2026-03-20T10:30:19.17719Z","steps":["trace[1604941813] 'process raft request' (duration: 72.724735ms)","trace[1604941813] 'compare' (duration: 121.362447ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:19.840465Z","caller":"traceutil/trace.go:171","msg":"trace[218286360] transaction","detail":"{read_only:false; response_revision:7797; number_of_response:1; }","duration":"128.101107ms","start":"2026-03-20T10:30:19.712345Z","end":"2026-03-20T10:30:19.840446Z","steps":["trace[218286360] 'process raft request' (duration: 128.059576ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:19.840526Z","caller":"traceutil/trace.go:171","msg":"trace[2080512713] transaction","detail":"{read_only:false; response_revision:7796; number_of_response:1; }","duration":"128.422805ms","start":"2026-03-20T10:30:19.712063Z","end":"2026-03-20T10:30:19.840486Z","steps":["trace[2080512713] 'process raft request' (duration: 79.65141ms)","trace[2080512713] 'compare' (duration: 48.560589ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:20.203986Z","caller":"traceutil/trace.go:171","msg":"trace[2114478902] linearizableReadLoop","detail":"{readStateIndex:8094; appliedIndex:8093; }","duration":"160.479169ms","start":"2026-03-20T10:30:20.043489Z","end":"2026-03-20T10:30:20.203968Z","steps":["trace[2114478902] 'read index received' (duration: 160.309805ms)","trace[2114478902] 'applied index is now lower than readState.Index' (duration: 168.634µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:20.204097Z","caller":"traceutil/trace.go:171","msg":"trace[1244347610] transaction","detail":"{read_only:false; response_revision:7798; number_of_response:1; }","duration":"165.517489ms","start":"2026-03-20T10:30:20.038535Z","end":"2026-03-20T10:30:20.204053Z","steps":["trace[1244347610] 'process raft request' (duration: 165.306444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:20.204173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.673503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l7vxp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-20T10:30:20.204194Z","caller":"traceutil/trace.go:171","msg":"trace[1001829571] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l7vxp; range_end:; response_count:1; response_revision:7798; }","duration":"160.747655ms","start":"2026-03-20T10:30:20.04344Z","end":"2026-03-20T10:30:20.204188Z","steps":["trace[1001829571] 'agreement among raft nodes before linearized reading' (duration: 160.615342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:35.009047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.50879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:30:35.009146Z","caller":"traceutil/trace.go:171","msg":"trace[658511879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7873; }","duration":"142.656484ms","start":"2026-03-20T10:30:34.866469Z","end":"2026-03-20T10:30:35.009125Z","steps":["trace[658511879] 'range keys from in-memory index tree' (duration: 142.354107ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:48.855333Z","caller":"traceutil/trace.go:171","msg":"trace[1285759525] linearizableReadLoop","detail":"{readStateIndex:8294; appliedIndex:8293; }","duration":"462.111886ms","start":"2026-03-20T10:30:48.393201Z","end":"2026-03-20T10:30:48.855313Z","steps":["trace[1285759525] 'read index received' (duration: 462.056024ms)","trace[1285759525] 'applied index is now lower than readState.Index' (duration: 54.922µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:48.85547Z","caller":"traceutil/trace.go:171","msg":"trace[1311976561] transaction","detail":"{read_only:false; response_revision:7989; number_of_response:1; }","duration":"467.455454ms","start":"2026-03-20T10:30:48.387999Z","end":"2026-03-20T10:30:48.855454Z","steps":["trace[1311976561] 'process raft request' (duration: 467.166137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:48.855584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:48.387978Z","time spent":"467.541036ms","remote":"127.0.0.1:39538","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-03-20T10:30:48.855743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.527335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l7vxp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-20T10:30:48.855809Z","caller":"traceutil/trace.go:171","msg":"trace[1360242905] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l7vxp; range_end:; response_count:1; response_revision:7989; }","duration":"462.613308ms","start":"2026-03-20T10:30:48.39318Z","end":"2026-03-20T10:30:48.855793Z","steps":["trace[1360242905] 'agreement among raft nodes before linearized reading' (duration: 462.2676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:48.85586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:48.393165Z","time spent":"462.682899ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-l7vxp\" "} {"level":"info","ts":"2026-03-20T10:30:49.092805Z","caller":"traceutil/trace.go:171","msg":"trace[753235198] linearizableReadLoop","detail":"{readStateIndex:8296; appliedIndex:8294; }","duration":"237.364624ms","start":"2026-03-20T10:30:48.855422Z","end":"2026-03-20T10:30:49.092787Z","steps":["trace[753235198] 'read index received' (duration: 237.263502ms)","trace[753235198] 'applied index is now lower than readState.Index' (duration: 100.482µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:30:49.092847Z","caller":"traceutil/trace.go:171","msg":"trace[2107370675] transaction","detail":"{read_only:false; response_revision:7990; number_of_response:1; }","duration":"505.929262ms","start":"2026-03-20T10:30:48.586902Z","end":"2026-03-20T10:30:49.092832Z","steps":["trace[2107370675] 'process raft request' (duration: 505.748118ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:49.092893Z","caller":"traceutil/trace.go:171","msg":"trace[2008975296] transaction","detail":"{read_only:false; response_revision:7991; number_of_response:1; }","duration":"265.873214ms","start":"2026-03-20T10:30:48.827005Z","end":"2026-03-20T10:30:49.092878Z","steps":["trace[2008975296] 'process raft request' (duration: 265.754522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:49.09297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.294632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-667ddf54ff-vjh4v.189e85f6e08c0505\" ","response":"range_response_count:1 size:1135"} {"level":"warn","ts":"2026-03-20T10:30:49.092967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:48.586874Z","time spent":"506.015984ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:30:49.092999Z","caller":"traceutil/trace.go:171","msg":"trace[389452200] range","detail":"{range_begin:/registry/events/openstack/barbican-api-667ddf54ff-vjh4v.189e85f6e08c0505; range_end:; response_count:1; response_revision:7991; }","duration":"483.370414ms","start":"2026-03-20T10:30:48.609621Z","end":"2026-03-20T10:30:49.092991Z","steps":["trace[389452200] 'agreement among raft nodes before linearized reading' (duration: 483.262732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:49.093019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:30:48.609597Z","time spent":"483.416215ms","remote":"127.0.0.1:39340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1158,"request content":"key:\"/registry/events/openstack/barbican-api-667ddf54ff-vjh4v.189e85f6e08c0505\" "} {"level":"info","ts":"2026-03-20T10:30:49.094825Z","caller":"traceutil/trace.go:171","msg":"trace[1803104590] transaction","detail":"{read_only:false; response_revision:7992; number_of_response:1; }","duration":"214.454017ms","start":"2026-03-20T10:30:48.880359Z","end":"2026-03-20T10:30:49.094813Z","steps":["trace[1803104590] 'process raft request' (duration: 214.252182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:49.094866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.092574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T10:30:49.094963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.031867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228909"} {"level":"info","ts":"2026-03-20T10:30:49.095017Z","caller":"traceutil/trace.go:171","msg":"trace[261696059] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:7992; }","duration":"235.112059ms","start":"2026-03-20T10:30:48.859893Z","end":"2026-03-20T10:30:49.095005Z","steps":["trace[261696059] 'agreement among raft nodes before linearized reading' (duration: 234.799412ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:30:49.095058Z","caller":"traceutil/trace.go:171","msg":"trace[1230448961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7992; }","duration":"231.189725ms","start":"2026-03-20T10:30:48.863733Z","end":"2026-03-20T10:30:49.094923Z","steps":["trace[1230448961] 'agreement among raft nodes before linearized reading' (duration: 231.052672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:30:52.926796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.897056ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-20T10:31:02.258158Z","caller":"traceutil/trace.go:171","msg":"trace[1320303703] transaction","detail":"{read_only:false; response_revision:8088; number_of_response:1; }","duration":"146.225659ms","start":"2026-03-20T10:31:02.111906Z","end":"2026-03-20T10:31:02.258131Z","steps":["trace[1320303703] 'process raft request' (duration: 66.524658ms)","trace[1320303703] 'compare' (duration: 79.359273ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:31:02.509536Z","caller":"traceutil/trace.go:171","msg":"trace[1916850384] linearizableReadLoop","detail":"{readStateIndex:8399; appliedIndex:8397; }","duration":"247.356151ms","start":"2026-03-20T10:31:02.262155Z","end":"2026-03-20T10:31:02.509511Z","steps":["trace[1916850384] 'read index received' (duration: 95.948749ms)","trace[1916850384] 'applied index is now lower than readState.Index' (duration: 151.406312ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:31:02.509646Z","caller":"traceutil/trace.go:171","msg":"trace[1940223227] transaction","detail":"{read_only:false; response_revision:8091; number_of_response:1; }","duration":"245.622281ms","start":"2026-03-20T10:31:02.263991Z","end":"2026-03-20T10:31:02.509613Z","steps":["trace[1940223227] 'process raft request' (duration: 245.433996ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:02.509674Z","caller":"traceutil/trace.go:171","msg":"trace[19528415] transaction","detail":"{read_only:false; response_revision:8090; number_of_response:1; }","duration":"247.588547ms","start":"2026-03-20T10:31:02.262061Z","end":"2026-03-20T10:31:02.50965Z","steps":["trace[19528415] 'process raft request' (duration: 247.028504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:31:02.509814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.638839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:499"} {"level":"info","ts":"2026-03-20T10:31:02.509869Z","caller":"traceutil/trace.go:171","msg":"trace[1621236343] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8091; }","duration":"247.73195ms","start":"2026-03-20T10:31:02.262119Z","end":"2026-03-20T10:31:02.509851Z","steps":["trace[1621236343] 'agreement among raft nodes before linearized reading' (duration: 247.555226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:31:02.581159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.709036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"warn","ts":"2026-03-20T10:31:02.581253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.078954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6qlrz\" ","response":"range_response_count:1 size:4740"} {"level":"info","ts":"2026-03-20T10:31:02.581269Z","caller":"traceutil/trace.go:171","msg":"trace[1753363361] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8091; }","duration":"305.855978ms","start":"2026-03-20T10:31:02.275393Z","end":"2026-03-20T10:31:02.581249Z","steps":["trace[1753363361] 'agreement among raft nodes before linearized reading' (duration: 305.631243ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:02.58131Z","caller":"traceutil/trace.go:171","msg":"trace[538653445] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6qlrz; range_end:; response_count:1; response_revision:8091; }","duration":"306.168896ms","start":"2026-03-20T10:31:02.275128Z","end":"2026-03-20T10:31:02.581296Z","steps":["trace[538653445] 'agreement among raft nodes before linearized reading' (duration: 305.979492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:31:02.581322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:31:02.275371Z","time spent":"305.942791ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":3089,"request content":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-03-20T10:31:02.58134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:31:02.275119Z","time spent":"306.212187ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4763,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6qlrz\" "} {"level":"info","ts":"2026-03-20T10:31:03.258486Z","caller":"traceutil/trace.go:171","msg":"trace[1000196731] transaction","detail":"{read_only:false; response_revision:8102; number_of_response:1; }","duration":"103.710665ms","start":"2026-03-20T10:31:03.154738Z","end":"2026-03-20T10:31:03.258449Z","steps":["trace[1000196731] 'process raft request' (duration: 103.451229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:31:03.609997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.619673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:31:03.61025Z","caller":"traceutil/trace.go:171","msg":"trace[862772862] transaction","detail":"{read_only:false; response_revision:8103; number_of_response:1; }","duration":"347.85353ms","start":"2026-03-20T10:31:03.262369Z","end":"2026-03-20T10:31:03.610222Z","steps":["trace[862772862] 'process raft request' (duration: 108.911279ms)","trace[862772862] 'compare' (duration: 238.138032ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:31:03.61035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:31:03.262356Z","time spent":"347.948922ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:31:03.610598Z","caller":"traceutil/trace.go:171","msg":"trace[431764529] transaction","detail":"{read_only:false; response_revision:8104; number_of_response:1; }","duration":"170.927388ms","start":"2026-03-20T10:31:03.439645Z","end":"2026-03-20T10:31:03.610572Z","steps":["trace[431764529] 'process raft request' (duration: 170.475528ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:03.663396Z","caller":"traceutil/trace.go:171","msg":"trace[1081093678] linearizableReadLoop","detail":"{readStateIndex:8415; appliedIndex:8412; }","duration":"137.118721ms","start":"2026-03-20T10:31:03.526257Z","end":"2026-03-20T10:31:03.663375Z","steps":["trace[1081093678] 'read index received' (duration: 34.753978ms)","trace[1081093678] 'applied index is now lower than readState.Index' (duration: 102.363853ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:31:03.663587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.312616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-20T10:31:03.663664Z","caller":"traceutil/trace.go:171","msg":"trace[646364136] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8105; }","duration":"137.406789ms","start":"2026-03-20T10:31:03.526241Z","end":"2026-03-20T10:31:03.663648Z","steps":["trace[646364136] 'agreement among raft nodes before linearized reading' (duration: 137.221974ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:03.663549Z","caller":"traceutil/trace.go:171","msg":"trace[1257808271] transaction","detail":"{read_only:false; response_revision:8105; number_of_response:1; }","duration":"212.783927ms","start":"2026-03-20T10:31:03.450647Z","end":"2026-03-20T10:31:03.663431Z","steps":["trace[1257808271] 'process raft request' (duration: 212.600562ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:04.251749Z","caller":"traceutil/trace.go:171","msg":"trace[2026174851] linearizableReadLoop","detail":"{readStateIndex:8420; appliedIndex:8419; }","duration":"124.984393ms","start":"2026-03-20T10:31:04.126746Z","end":"2026-03-20T10:31:04.251731Z","steps":["trace[2026174851] 'read index received' (duration: 124.88558ms)","trace[2026174851] 'applied index is now lower than readState.Index' (duration: 97.603µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:31:04.251848Z","caller":"traceutil/trace.go:171","msg":"trace[1213892755] transaction","detail":"{read_only:false; response_revision:8110; number_of_response:1; }","duration":"136.63459ms","start":"2026-03-20T10:31:04.115193Z","end":"2026-03-20T10:31:04.251828Z","steps":["trace[1213892755] 'process raft request' (duration: 136.404065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:31:04.251913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.165327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-03-20T10:31:04.251938Z","caller":"traceutil/trace.go:171","msg":"trace[911410055] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8110; }","duration":"125.204577ms","start":"2026-03-20T10:31:04.126726Z","end":"2026-03-20T10:31:04.25193Z","steps":["trace[911410055] 'agreement among raft nodes before linearized reading' (duration: 125.076734ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:04.423361Z","caller":"traceutil/trace.go:171","msg":"trace[964875442] transaction","detail":"{read_only:false; response_revision:8111; number_of_response:1; }","duration":"135.361779ms","start":"2026-03-20T10:31:04.287975Z","end":"2026-03-20T10:31:04.423337Z","steps":["trace[964875442] 'process raft request' (duration: 109.852731ms)","trace[964875442] 'compare' (duration: 25.306693ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:31:04.607974Z","caller":"traceutil/trace.go:171","msg":"trace[1271502670] transaction","detail":"{read_only:false; response_revision:8112; number_of_response:1; }","duration":"180.527247ms","start":"2026-03-20T10:31:04.427421Z","end":"2026-03-20T10:31:04.607948Z","steps":["trace[1271502670] 'process raft request' (duration: 129.884049ms)","trace[1271502670] 'compare' (duration: 50.326631ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:31:05.085874Z","caller":"traceutil/trace.go:171","msg":"trace[567122311] transaction","detail":"{read_only:false; response_revision:8116; number_of_response:1; }","duration":"106.838169ms","start":"2026-03-20T10:31:04.979008Z","end":"2026-03-20T10:31:05.085846Z","steps":["trace[567122311] 'process raft request' (duration: 58.444433ms)","trace[567122311] 'compare' (duration: 48.247732ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:31:05.099406Z","caller":"traceutil/trace.go:171","msg":"trace[1670704283] transaction","detail":"{read_only:false; response_revision:8117; number_of_response:1; }","duration":"119.925012ms","start":"2026-03-20T10:31:04.979455Z","end":"2026-03-20T10:31:05.09938Z","steps":["trace[1670704283] 'process raft request' (duration: 119.754547ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:05.791338Z","caller":"traceutil/trace.go:171","msg":"trace[1889157863] transaction","detail":"{read_only:false; response_revision:8122; number_of_response:1; }","duration":"126.175371ms","start":"2026-03-20T10:31:05.66513Z","end":"2026-03-20T10:31:05.791305Z","steps":["trace[1889157863] 'process raft request' (duration: 117.962626ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:31:05.825954Z","caller":"traceutil/trace.go:171","msg":"trace[1229913727] transaction","detail":"{read_only:false; response_revision:8123; number_of_response:1; }","duration":"156.569926ms","start":"2026-03-20T10:31:05.669356Z","end":"2026-03-20T10:31:05.825926Z","steps":["trace[1229913727] 'process raft request' (duration: 156.422463ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:32:07.990496Z","caller":"traceutil/trace.go:171","msg":"trace[220420617] transaction","detail":"{read_only:false; response_revision:8747; number_of_response:1; }","duration":"109.657109ms","start":"2026-03-20T10:32:07.880804Z","end":"2026-03-20T10:32:07.990461Z","steps":["trace[220420617] 'process raft request' (duration: 109.398562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:32:22.52791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.303031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-20T10:32:22.527953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.318228ms","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-03-20T10:32:22.528071Z","caller":"traceutil/trace.go:171","msg":"trace[986840261] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:8822; }","duration":"280.520177ms","start":"2026-03-20T10:32:22.247526Z","end":"2026-03-20T10:32:22.528046Z","steps":["trace[986840261] 'count revisions from in-memory index tree' (duration: 280.220809ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:32:22.528107Z","caller":"traceutil/trace.go:171","msg":"trace[507932180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8822; }","duration":"269.541704ms","start":"2026-03-20T10:32:22.258542Z","end":"2026-03-20T10:32:22.528084Z","steps":["trace[507932180] 'range keys from in-memory index tree' (duration: 269.000352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:32:22.528095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.476191ms","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-03-20T10:32:22.528192Z","caller":"traceutil/trace.go:171","msg":"trace[1414854174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8822; }","duration":"111.643346ms","start":"2026-03-20T10:32:22.416528Z","end":"2026-03-20T10:32:22.528171Z","steps":["trace[1414854174] 'range keys from in-memory index tree' (duration: 111.173134ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:32:22.693919Z","caller":"traceutil/trace.go:171","msg":"trace[793808024] transaction","detail":"{read_only:false; response_revision:8824; number_of_response:1; }","duration":"161.927485ms","start":"2026-03-20T10:32:22.531893Z","end":"2026-03-20T10:32:22.69382Z","steps":["trace[793808024] 'process raft request' (duration: 119.643927ms)","trace[793808024] 'compare' (duration: 42.092214ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:33:12.040438Z","caller":"traceutil/trace.go:171","msg":"trace[1632923910] transaction","detail":"{read_only:false; response_revision:9148; number_of_response:1; }","duration":"158.543024ms","start":"2026-03-20T10:33:11.881871Z","end":"2026-03-20T10:33:12.040414Z","steps":["trace[1632923910] 'process raft request' (duration: 94.859624ms)","trace[1632923910] 'compare' (duration: 63.593017ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:34:25.125427Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3e4829a3cabe4fce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-20T10:34:25.130798Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-20T10:34:25.130946Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-20T10:34:38.175309Z","caller":"traceutil/trace.go:171","msg":"trace[1001687120] transaction","detail":"{read_only:false; response_revision:9791; number_of_response:1; }","duration":"114.957792ms","start":"2026-03-20T10:34:38.060329Z","end":"2026-03-20T10:34:38.175286Z","steps":["trace[1001687120] 'process raft request' (duration: 114.803569ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:35:04.30894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7721} {"level":"info","ts":"2026-03-20T10:35:04.375047Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7721,"took":"63.948775ms","hash":1697391195,"current-db-size-bytes":42737664,"current-db-size":"43 MB","current-db-size-in-use-bytes":25436160,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-20T10:35:04.375105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1697391195,"revision":7721,"compact-revision":5699} {"level":"info","ts":"2026-03-20T10:40:04.315405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9961} {"level":"info","ts":"2026-03-20T10:40:04.391427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9961,"took":"74.202208ms","hash":1883169442,"current-db-size-bytes":42737664,"current-db-size":"43 MB","current-db-size-in-use-bytes":17575936,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-20T10:40:04.391502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1883169442,"revision":9961,"compact-revision":7721}