{"level":"warn","ts":"2026-04-07T12:52:54.771254Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T12:52:54.771347Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.224: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.224:2380","--initial-cluster=instance=https://199.19.213.224:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.224:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.224:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T12:52:54.771417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T12:52:54.771436Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-04-07T12:52:54.771464Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T12:52:54.772071Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"]} {"level":"info","ts":"2026-04-07T12:52:54.77214Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.224:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T12:52:54.776147Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.800764ms"} {"level":"info","ts":"2026-04-07T12:52:54.785713Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9870e1635c99d2ec","cluster-id":"185398c074a8a76b"} {"level":"info","ts":"2026-04-07T12:52:54.785818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:52:54.785903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 0"} {"level":"info","ts":"2026-04-07T12:52:54.785925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9870e1635c99d2ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T12:52:54.785971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 1"} {"level":"info","ts":"2026-04-07T12:52:54.786071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"warn","ts":"2026-04-07T12:52:54.793784Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:52:54.797761Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:52:54.799818Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T12:52:54.803087Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9870e1635c99d2ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T12:52:54.803418Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9870e1635c99d2ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T12:52:54.803586Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T12:52:54.803686Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T12:52:54.803697Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T12:52:54.804294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"info","ts":"2026-04-07T12:52:54.804489Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","added-peer-id":"9870e1635c99d2ec","added-peer-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-04-07T12:52:54.807376Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T12:52:54.807442Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-04-07T12:52:54.807492Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-04-07T12:52:54.807639Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9870e1635c99d2ec","initial-advertise-peer-urls":["https://199.19.213.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T12:52:54.807685Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:52:55.494252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:52:55.494343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:52:55.494368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgPreVoteResp from 9870e1635c99d2ec at term 1"} {"level":"info","ts":"2026-04-07T12:52:55.49439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:52:55.494403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgVoteResp from 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-04-07T12:52:55.494419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became leader at term 2"} {"level":"info","ts":"2026-04-07T12:52:55.494432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9870e1635c99d2ec elected leader 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-04-07T12:52:55.49641Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:52:55.497236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:52:55.49725Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:52:55.497233Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9870e1635c99d2ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.224:2379]}","request-path":"/0/members/9870e1635c99d2ec/attributes","cluster-id":"185398c074a8a76b","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T12:52:55.497659Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:52:55.497789Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:52:55.497843Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:52:55.497516Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:52:55.498191Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:52:55.499078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.224:2379"} {"level":"info","ts":"2026-04-07T12:52:55.499693Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:52:58.07386Z","caller":"traceutil/trace.go:171","msg":"trace[2144209877] linearizableReadLoop","detail":"{readStateIndex:114; appliedIndex:113; }","duration":"142.230398ms","start":"2026-04-07T12:52:57.931609Z","end":"2026-04-07T12:52:58.07384Z","steps":["trace[2144209877] 'read index received' (duration: 142.006732ms)","trace[2144209877] 'applied index is now lower than readState.Index' (duration: 222.846µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:52:58.073961Z","caller":"traceutil/trace.go:171","msg":"trace[1506104728] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"143.701375ms","start":"2026-04-07T12:52:57.930252Z","end":"2026-04-07T12:52:58.073954Z","steps":["trace[1506104728] 'process raft request' (duration: 143.447688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:58.07427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.572696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T12:52:58.074516Z","caller":"traceutil/trace.go:171","msg":"trace[630914162] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:109; }","duration":"142.939395ms","start":"2026-04-07T12:52:57.931559Z","end":"2026-04-07T12:52:58.074498Z","steps":["trace[630914162] 'agreement among raft nodes before linearized reading' (duration: 142.581636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:58.82188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.645998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-07T12:52:58.821979Z","caller":"traceutil/trace.go:171","msg":"trace[1437830103] linearizableReadLoop","detail":"{readStateIndex:115; appliedIndex:114; }","duration":"742.625896ms","start":"2026-04-07T12:52:58.079338Z","end":"2026-04-07T12:52:58.821964Z","steps":["trace[1437830103] 'read index received' (duration: 499.429345ms)","trace[1437830103] 'applied index is now lower than readState.Index' (duration: 243.195261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:52:58.822115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.76812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T12:52:58.822152Z","caller":"traceutil/trace.go:171","msg":"trace[119941901] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:110; }","duration":"742.829501ms","start":"2026-04-07T12:52:58.079314Z","end":"2026-04-07T12:52:58.822143Z","steps":["trace[119941901] 'agreement among raft nodes before linearized reading' (duration: 742.692567ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:52:58.822319Z","caller":"traceutil/trace.go:171","msg":"trace[1783703196] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"742.980835ms","start":"2026-04-07T12:52:58.079309Z","end":"2026-04-07T12:52:58.82229Z","steps":["trace[1783703196] 'process raft request' (duration: 499.439785ms)","trace[1783703196] 'compare' (duration: 242.522345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:52:58.822319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:58.079273Z","time spent":"742.967954ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-04-07T12:52:58.822459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:58.079291Z","time spent":"743.104508ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:52:59.801046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.473566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-07T12:52:59.801338Z","caller":"traceutil/trace.go:171","msg":"trace[2085868551] linearizableReadLoop","detail":"{readStateIndex:116; appliedIndex:115; }","duration":"771.562272ms","start":"2026-04-07T12:52:59.029746Z","end":"2026-04-07T12:52:59.801308Z","steps":["trace[2085868551] 'read index received' (duration: 328.785229ms)","trace[2085868551] 'applied index is now lower than readState.Index' (duration: 442.774513ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:52:59.801374Z","caller":"traceutil/trace.go:171","msg":"trace[1013283350] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"968.413127ms","start":"2026-04-07T12:52:58.832925Z","end":"2026-04-07T12:52:59.801338Z","steps":["trace[1013283350] 'process raft request' (duration: 525.583222ms)","trace[1013283350] 'compare' (duration: 442.357943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:52:59.801689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:58.832906Z","time spent":"968.705144ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:52:59.801795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.999803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T12:52:59.801844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.222605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T12:52:59.801994Z","caller":"traceutil/trace.go:171","msg":"trace[2025826746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:111; }","duration":"772.208538ms","start":"2026-04-07T12:52:59.029703Z","end":"2026-04-07T12:52:59.801912Z","steps":["trace[2025826746] 'agreement among raft nodes before linearized reading' (duration: 771.734486ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:52:59.802205Z","caller":"traceutil/trace.go:171","msg":"trace[757798088] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:111; }","duration":"245.490232ms","start":"2026-04-07T12:52:59.556602Z","end":"2026-04-07T12:52:59.802092Z","steps":["trace[757798088] 'agreement among raft nodes before linearized reading' (duration: 245.097462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:52:59.802199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:52:59.029683Z","time spent":"772.443274ms","remote":"127.0.0.1:56988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T12:53:02.825824Z","caller":"traceutil/trace.go:171","msg":"trace[1558477415] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"180.829293ms","start":"2026-04-07T12:53:02.644968Z","end":"2026-04-07T12:53:02.825797Z","steps":["trace[1558477415] 'process raft request' (duration: 179.207513ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:02.825902Z","caller":"traceutil/trace.go:171","msg":"trace[1424270196] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"173.632256ms","start":"2026-04-07T12:53:02.652236Z","end":"2026-04-07T12:53:02.825868Z","steps":["trace[1424270196] 'process raft request' (duration: 173.491622ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:02.826001Z","caller":"traceutil/trace.go:171","msg":"trace[1291633425] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"173.592275ms","start":"2026-04-07T12:53:02.652357Z","end":"2026-04-07T12:53:02.82595Z","steps":["trace[1291633425] 'process raft request' (duration: 173.415439ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:02.826054Z","caller":"traceutil/trace.go:171","msg":"trace[1493365038] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"173.215336ms","start":"2026-04-07T12:53:02.652766Z","end":"2026-04-07T12:53:02.825982Z","steps":["trace[1493365038] 'process raft request' (duration: 173.072942ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:02.826141Z","caller":"traceutil/trace.go:171","msg":"trace[1117244608] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"172.418366ms","start":"2026-04-07T12:53:02.653713Z","end":"2026-04-07T12:53:02.826131Z","steps":["trace[1117244608] 'process raft request' (duration: 172.1856ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:02.826236Z","caller":"traceutil/trace.go:171","msg":"trace[1804558150] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"173.454732ms","start":"2026-04-07T12:53:02.652712Z","end":"2026-04-07T12:53:02.826167Z","steps":["trace[1804558150] 'process raft request' (duration: 173.096032ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:02.82632Z","caller":"traceutil/trace.go:171","msg":"trace[1884741725] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:280; }","duration":"174.730513ms","start":"2026-04-07T12:53:02.651567Z","end":"2026-04-07T12:53:02.826298Z","steps":["trace[1884741725] 'read index received' (duration: 66.435795ms)","trace[1884741725] 'applied index is now lower than readState.Index' (duration: 108.292498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:02.826528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.939708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T12:53:02.826572Z","caller":"traceutil/trace.go:171","msg":"trace[2129759219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:279; }","duration":"175.01964ms","start":"2026-04-07T12:53:02.651543Z","end":"2026-04-07T12:53:02.826562Z","steps":["trace[2129759219] 'agreement among raft nodes before linearized reading' (duration: 174.850726ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:02.828314Z","caller":"traceutil/trace.go:171","msg":"trace[94397164] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"106.703047ms","start":"2026-04-07T12:53:02.72159Z","end":"2026-04-07T12:53:02.828293Z","steps":["trace[94397164] 'process raft request' (duration: 106.508343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:02.828568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.247305ms","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-04-07T12:53:02.828628Z","caller":"traceutil/trace.go:171","msg":"trace[1526720347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:280; }","duration":"175.326597ms","start":"2026-04-07T12:53:02.653287Z","end":"2026-04-07T12:53:02.828613Z","steps":["trace[1526720347] 'agreement among raft nodes before linearized reading' (duration: 175.175753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:02.828847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.571929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-07T12:53:02.829002Z","caller":"traceutil/trace.go:171","msg":"trace[907020027] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:280; }","duration":"109.664781ms","start":"2026-04-07T12:53:02.719217Z","end":"2026-04-07T12:53:02.828882Z","steps":["trace[907020027] 'agreement among raft nodes before linearized reading' (duration: 109.579429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:10.58454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.212775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:53:10.584623Z","caller":"traceutil/trace.go:171","msg":"trace[1203706723] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"275.248685ms","start":"2026-04-07T12:53:10.309362Z","end":"2026-04-07T12:53:10.58461Z","steps":["trace[1203706723] 'read index received' (duration: 160.680912ms)","trace[1203706723] 'applied index is now lower than readState.Index' (duration: 114.566823ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:10.584669Z","caller":"traceutil/trace.go:171","msg":"trace[2002452781] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"280.868333ms","start":"2026-04-07T12:53:10.303771Z","end":"2026-04-07T12:53:10.584639Z","steps":["trace[2002452781] 'process raft request' (duration: 166.30699ms)","trace[2002452781] 'compare' (duration: 114.113992ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:10.584851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.491632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:53:10.584887Z","caller":"traceutil/trace.go:171","msg":"trace[1238088861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"275.544762ms","start":"2026-04-07T12:53:10.309331Z","end":"2026-04-07T12:53:10.584876Z","steps":["trace[1238088861] 'agreement among raft nodes before linearized reading' (duration: 275.310467ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:28.407188Z","caller":"traceutil/trace.go:171","msg":"trace[2022097126] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"497.481537ms","start":"2026-04-07T12:53:27.909684Z","end":"2026-04-07T12:53:28.407165Z","steps":["trace[2022097126] 'process raft request' (duration: 497.382325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:28.407321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.909661Z","time spent":"497.600381ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:28.457733Z","caller":"traceutil/trace.go:171","msg":"trace[1816156231] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"543.161635ms","start":"2026-04-07T12:53:27.914544Z","end":"2026-04-07T12:53:28.457706Z","steps":["trace[1816156231] 'process raft request' (duration: 542.933389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:28.457874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.914528Z","time spent":"543.275117ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:28.457872Z","caller":"traceutil/trace.go:171","msg":"trace[1542740957] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"543.244087ms","start":"2026-04-07T12:53:27.914593Z","end":"2026-04-07T12:53:28.457837Z","steps":["trace[1542740957] 'process raft request' (duration: 542.997531ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:28.458005Z","caller":"traceutil/trace.go:171","msg":"trace[1687852146] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"531.422079ms","start":"2026-04-07T12:53:27.926572Z","end":"2026-04-07T12:53:28.457994Z","steps":["trace[1687852146] 'process raft request' (duration: 531.177414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:28.458004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.914589Z","time spent":"543.357649ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:28.458129Z","caller":"traceutil/trace.go:171","msg":"trace[1520224743] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"537.165948ms","start":"2026-04-07T12:53:27.920955Z","end":"2026-04-07T12:53:28.458121Z","steps":["trace[1520224743] 'process raft request' (duration: 536.751909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:28.458095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.926547Z","time spent":"531.521492ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:53:28.458168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.920942Z","time spent":"537.20884ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:28.458225Z","caller":"traceutil/trace.go:171","msg":"trace[1947424683] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"528.800806ms","start":"2026-04-07T12:53:27.929413Z","end":"2026-04-07T12:53:28.458213Z","steps":["trace[1947424683] 'process raft request' (duration: 528.453567ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:28.458267Z","caller":"traceutil/trace.go:171","msg":"trace[2052058743] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"524.46901ms","start":"2026-04-07T12:53:27.933782Z","end":"2026-04-07T12:53:28.458251Z","steps":["trace[2052058743] 'process raft request' (duration: 524.127622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:28.458284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.929396Z","time spent":"528.860698ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:53:28.458427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.933769Z","time spent":"524.553643ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:28.458473Z","caller":"traceutil/trace.go:171","msg":"trace[1851567316] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"520.67084ms","start":"2026-04-07T12:53:27.937674Z","end":"2026-04-07T12:53:28.458345Z","steps":["trace[1851567316] 'process raft request' (duration: 520.479214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:28.458569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.937654Z","time spent":"520.875804ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:28.458651Z","caller":"traceutil/trace.go:171","msg":"trace[1800408815] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"518.725442ms","start":"2026-04-07T12:53:27.93991Z","end":"2026-04-07T12:53:28.458635Z","steps":["trace[1800408815] 'process raft request' (duration: 518.404285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:28.458721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.939895Z","time spent":"518.794065ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:28.458753Z","caller":"traceutil/trace.go:171","msg":"trace[929841171] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"501.970226ms","start":"2026-04-07T12:53:27.956767Z","end":"2026-04-07T12:53:28.458737Z","steps":["trace[929841171] 'process raft request' (duration: 501.827743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:28.458883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.95674Z","time spent":"502.070529ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:28.459067Z","caller":"traceutil/trace.go:171","msg":"trace[1853255492] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"387.026439ms","start":"2026-04-07T12:53:28.071984Z","end":"2026-04-07T12:53:28.45901Z","steps":["trace[1853255492] 'process raft request' (duration: 386.744803ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:28.459125Z","caller":"traceutil/trace.go:171","msg":"trace[377427261] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:536; }","duration":"485.998918ms","start":"2026-04-07T12:53:27.973105Z","end":"2026-04-07T12:53:28.459104Z","steps":["trace[377427261] 'read index received' (duration: 434.088091ms)","trace[377427261] 'applied index is now lower than readState.Index' (duration: 51.908827ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:28.459189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:28.071961Z","time spent":"387.172413ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:28.458312Z","caller":"traceutil/trace.go:171","msg":"trace[863656148] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"530.675092ms","start":"2026-04-07T12:53:27.927578Z","end":"2026-04-07T12:53:28.458254Z","steps":["trace[863656148] 'process raft request' (duration: 530.212281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:28.459405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.251244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T12:53:28.459448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.927539Z","time spent":"531.83673ms","remote":"127.0.0.1:57022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:28.459476Z","caller":"traceutil/trace.go:171","msg":"trace[686452808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"486.382558ms","start":"2026-04-07T12:53:27.973078Z","end":"2026-04-07T12:53:28.45946Z","steps":["trace[686452808] 'agreement among raft nodes before linearized reading' (duration: 486.135062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:28.459572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:27.973069Z","time spent":"486.49295ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T12:53:28.462208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.290201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:53:28.462267Z","caller":"traceutil/trace.go:171","msg":"trace[1648086340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"150.377963ms","start":"2026-04-07T12:53:28.311873Z","end":"2026-04-07T12:53:28.462251Z","steps":["trace[1648086340] 'agreement among raft nodes before linearized reading' (duration: 150.289851ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:31.208906Z","caller":"traceutil/trace.go:171","msg":"trace[602642604] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"109.756147ms","start":"2026-04-07T12:53:31.099126Z","end":"2026-04-07T12:53:31.208882Z","steps":["trace[602642604] 'process raft request' (duration: 105.640977ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:32.396226Z","caller":"traceutil/trace.go:171","msg":"trace[1730840776] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"161.228972ms","start":"2026-04-07T12:53:32.234955Z","end":"2026-04-07T12:53:32.396184Z","steps":["trace[1730840776] 'process raft request' (duration: 157.245675ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:34.78906Z","caller":"traceutil/trace.go:171","msg":"trace[933770003] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"136.677566ms","start":"2026-04-07T12:53:34.652359Z","end":"2026-04-07T12:53:34.789037Z","steps":["trace[933770003] 'process raft request' (duration: 136.41275ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:35.315172Z","caller":"traceutil/trace.go:171","msg":"trace[76202329] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"209.28512ms","start":"2026-04-07T12:53:35.105863Z","end":"2026-04-07T12:53:35.315148Z","steps":["trace[76202329] 'read index received' (duration: 208.396049ms)","trace[76202329] 'applied index is now lower than readState.Index' (duration: 887.881µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:35.315304Z","caller":"traceutil/trace.go:171","msg":"trace[123796868] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"427.483645ms","start":"2026-04-07T12:53:34.887774Z","end":"2026-04-07T12:53:35.315258Z","steps":["trace[123796868] 'process raft request' (duration: 426.784188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:35.315393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.520346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T12:53:35.315434Z","caller":"traceutil/trace.go:171","msg":"trace[782633337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"209.579737ms","start":"2026-04-07T12:53:35.105844Z","end":"2026-04-07T12:53:35.315424Z","steps":["trace[782633337] 'agreement among raft nodes before linearized reading' (duration: 209.399033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:35.315492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:34.887746Z","time spent":"427.651199ms","remote":"127.0.0.1:57072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:36.277287Z","caller":"traceutil/trace.go:171","msg":"trace[695546642] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"957.364082ms","start":"2026-04-07T12:53:35.319902Z","end":"2026-04-07T12:53:36.277267Z","steps":["trace[695546642] 'process raft request' (duration: 957.178418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:36.277418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:35.319883Z","time spent":"957.464684ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:36.424525Z","caller":"traceutil/trace.go:171","msg":"trace[557047989] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.104275503s","start":"2026-04-07T12:53:35.320219Z","end":"2026-04-07T12:53:36.424494Z","steps":["trace[557047989] 'process raft request' (duration: 1.037469088s)","trace[557047989] 'compare' (duration: 66.542998ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:36.424658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:35.320198Z","time spent":"1.104399346s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:36.424752Z","caller":"traceutil/trace.go:171","msg":"trace[1438132412] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.044828524s","start":"2026-04-07T12:53:35.379918Z","end":"2026-04-07T12:53:36.424746Z","steps":["trace[1438132412] 'process raft request' (duration: 1.044472156s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:36.424787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:35.379897Z","time spent":"1.044867416s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:53:36.67746Z","caller":"traceutil/trace.go:171","msg":"trace[901769558] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"149.78122ms","start":"2026-04-07T12:53:36.527653Z","end":"2026-04-07T12:53:36.677435Z","steps":["trace[901769558] 'read index received' (duration: 149.575655ms)","trace[901769558] 'applied index is now lower than readState.Index' (duration: 204.545µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:36.677698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.026626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T12:53:36.677771Z","caller":"traceutil/trace.go:171","msg":"trace[423632699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"150.115638ms","start":"2026-04-07T12:53:36.527618Z","end":"2026-04-07T12:53:36.677734Z","steps":["trace[423632699] 'agreement among raft nodes before linearized reading' (duration: 149.921853ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:36.678179Z","caller":"traceutil/trace.go:171","msg":"trace[904220673] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"177.077399ms","start":"2026-04-07T12:53:36.501084Z","end":"2026-04-07T12:53:36.678162Z","steps":["trace[904220673] 'process raft request' (duration: 176.207689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:37.813092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198695912483817254,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T12:53:38.041875Z","caller":"traceutil/trace.go:171","msg":"trace[446687997] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.357730313s","start":"2026-04-07T12:53:36.684085Z","end":"2026-04-07T12:53:38.041815Z","steps":["trace[446687997] 'process raft request' (duration: 1.356983045s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:38.042093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:36.684064Z","time spent":"1.357897746s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:38.070182Z","caller":"traceutil/trace.go:171","msg":"trace[508490525] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:656; }","duration":"757.681384ms","start":"2026-04-07T12:53:37.312453Z","end":"2026-04-07T12:53:38.070134Z","steps":["trace[508490525] 'read index received' (duration: 729.014522ms)","trace[508490525] 'applied index is now lower than readState.Index' (duration: 28.665842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:38.070281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.810928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:53:38.070308Z","caller":"traceutil/trace.go:171","msg":"trace[60479274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"757.88729ms","start":"2026-04-07T12:53:37.312413Z","end":"2026-04-07T12:53:38.0703Z","steps":["trace[60479274] 'agreement among raft nodes before linearized reading' (duration: 757.826198ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:38.07026Z","caller":"traceutil/trace.go:171","msg":"trace[1769860162] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.222981935s","start":"2026-04-07T12:53:36.84721Z","end":"2026-04-07T12:53:38.070192Z","steps":["trace[1769860162] 'process raft request' (duration: 1.222424772s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:38.07033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:37.312392Z","time spent":"757.93331ms","remote":"127.0.0.1:56988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T12:53:38.070306Z","caller":"traceutil/trace.go:171","msg":"trace[1508761300] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"546.913013ms","start":"2026-04-07T12:53:37.52335Z","end":"2026-04-07T12:53:38.070263Z","steps":["trace[1508761300] 'process raft request' (duration: 546.741708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:38.070471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:36.847181Z","time spent":"1.223172931s","remote":"127.0.0.1:57072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:53:38.070525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:37.523323Z","time spent":"547.115017ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:41.51255Z","caller":"traceutil/trace.go:171","msg":"trace[500082321] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"109.630595ms","start":"2026-04-07T12:53:41.402898Z","end":"2026-04-07T12:53:41.512528Z","steps":["trace[500082321] 'process raft request' (duration: 109.509182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:43.046472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.87068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:53:43.046649Z","caller":"traceutil/trace.go:171","msg":"trace[1451367227] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"312.760276ms","start":"2026-04-07T12:53:42.733864Z","end":"2026-04-07T12:53:43.046624Z","steps":["trace[1451367227] 'process raft request' (duration: 139.625559ms)","trace[1451367227] 'compare' (duration: 172.694205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:43.046722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:42.733828Z","time spent":"312.863958ms","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18322,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:43.048052Z","caller":"traceutil/trace.go:171","msg":"trace[965335224] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:734; }","duration":"235.338377ms","start":"2026-04-07T12:53:42.812694Z","end":"2026-04-07T12:53:43.048032Z","steps":["trace[965335224] 'read index received' (duration: 60.822407ms)","trace[965335224] 'applied index is now lower than readState.Index' (duration: 174.514589ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:43.048188Z","caller":"traceutil/trace.go:171","msg":"trace[1511279549] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"251.160159ms","start":"2026-04-07T12:53:42.796993Z","end":"2026-04-07T12:53:43.048153Z","steps":["trace[1511279549] 'process raft request' (duration: 250.935104ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:43.048173Z","caller":"traceutil/trace.go:171","msg":"trace[1468930383] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"271.10775ms","start":"2026-04-07T12:53:42.777006Z","end":"2026-04-07T12:53:43.048114Z","steps":["trace[1468930383] 'process raft request' (duration: 270.576747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:43.048586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.886959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T12:53:43.048649Z","caller":"traceutil/trace.go:171","msg":"trace[845633721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:710; }","duration":"235.976852ms","start":"2026-04-07T12:53:42.812656Z","end":"2026-04-07T12:53:43.048633Z","steps":["trace[845633721] 'agreement among raft nodes before linearized reading' (duration: 235.740096ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:43.400358Z","caller":"traceutil/trace.go:171","msg":"trace[228851707] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"128.956721ms","start":"2026-04-07T12:53:43.271375Z","end":"2026-04-07T12:53:43.400332Z","steps":["trace[228851707] 'process raft request' (duration: 128.707575ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:51.46546Z","caller":"traceutil/trace.go:171","msg":"trace[354666291] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"152.464692ms","start":"2026-04-07T12:53:51.312978Z","end":"2026-04-07T12:53:51.465443Z","steps":["trace[354666291] 'read index received' (duration: 152.315377ms)","trace[354666291] 'applied index is now lower than readState.Index' (duration: 148.585µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:53:51.465496Z","caller":"traceutil/trace.go:171","msg":"trace[786577972] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"172.557369ms","start":"2026-04-07T12:53:51.292919Z","end":"2026-04-07T12:53:51.465476Z","steps":["trace[786577972] 'process raft request' (duration: 172.426695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:51.46559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.583965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:53:51.465618Z","caller":"traceutil/trace.go:171","msg":"trace[261878245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"152.671197ms","start":"2026-04-07T12:53:51.312937Z","end":"2026-04-07T12:53:51.465609Z","steps":["trace[261878245] 'agreement among raft nodes before linearized reading' (duration: 152.579745ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:53:53.277377Z","caller":"traceutil/trace.go:171","msg":"trace[1372487820] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"148.932282ms","start":"2026-04-07T12:53:53.128411Z","end":"2026-04-07T12:53:53.277343Z","steps":["trace[1372487820] 'process raft request' (duration: 148.779929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:56.130572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198695912483817650,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T12:53:56.182585Z","caller":"traceutil/trace.go:171","msg":"trace[1442382873] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"896.872031ms","start":"2026-04-07T12:53:55.285686Z","end":"2026-04-07T12:53:56.182558Z","steps":["trace[1442382873] 'process raft request' (duration: 896.570673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:56.182753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:55.285665Z","time spent":"897.007854ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:56.19159Z","caller":"traceutil/trace.go:171","msg":"trace[1521176221] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"406.977554ms","start":"2026-04-07T12:53:55.784583Z","end":"2026-04-07T12:53:56.19156Z","steps":["trace[1521176221] 'process raft request' (duration: 406.757008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:56.19174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:55.78456Z","time spent":"407.109206ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:53:56.196728Z","caller":"traceutil/trace.go:171","msg":"trace[905644744] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:829; }","duration":"566.407551ms","start":"2026-04-07T12:53:55.63031Z","end":"2026-04-07T12:53:56.196718Z","steps":["trace[905644744] 'read index received' (duration: 552.083828ms)","trace[905644744] 'applied index is now lower than readState.Index' (duration: 14.321963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:53:56.19694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.629457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:53:56.196978Z","caller":"traceutil/trace.go:171","msg":"trace[988657987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"566.705939ms","start":"2026-04-07T12:53:55.630262Z","end":"2026-04-07T12:53:56.196968Z","steps":["trace[988657987] 'agreement among raft nodes before linearized reading' (duration: 566.549465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:53:56.197007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:53:55.630242Z","time spent":"566.7562ms","remote":"127.0.0.1:57238","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":"info","ts":"2026-04-07T12:54:00.19846Z","caller":"traceutil/trace.go:171","msg":"trace[425037816] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"144.949604ms","start":"2026-04-07T12:54:00.053487Z","end":"2026-04-07T12:54:00.198436Z","steps":["trace[425037816] 'process raft request' (duration: 144.739959ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.198665Z","caller":"traceutil/trace.go:171","msg":"trace[1887056785] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"144.861372ms","start":"2026-04-07T12:54:00.053777Z","end":"2026-04-07T12:54:00.198638Z","steps":["trace[1887056785] 'process raft request' (duration: 144.568675ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.198847Z","caller":"traceutil/trace.go:171","msg":"trace[474332387] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"143.669862ms","start":"2026-04-07T12:54:00.055151Z","end":"2026-04-07T12:54:00.198821Z","steps":["trace[474332387] 'process raft request' (duration: 143.455097ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.393412Z","caller":"traceutil/trace.go:171","msg":"trace[1778517837] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"109.459051ms","start":"2026-04-07T12:54:00.283918Z","end":"2026-04-07T12:54:00.393377Z","steps":["trace[1778517837] 'process raft request' (duration: 107.500293ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.601633Z","caller":"traceutil/trace.go:171","msg":"trace[1358812667] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"315.378365ms","start":"2026-04-07T12:54:00.286198Z","end":"2026-04-07T12:54:00.601576Z","steps":["trace[1358812667] 'process raft request' (duration: 276.844957ms)","trace[1358812667] 'compare' (duration: 37.956713ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:54:00.601914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:00.286174Z","time spent":"315.64019ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:54:00.604182Z","caller":"traceutil/trace.go:171","msg":"trace[48489570] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:878; }","duration":"177.972087ms","start":"2026-04-07T12:54:00.426186Z","end":"2026-04-07T12:54:00.604158Z","steps":["trace[48489570] 'read index received' (duration: 136.763883ms)","trace[48489570] 'applied index is now lower than readState.Index' (duration: 41.207014ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:00.604245Z","caller":"traceutil/trace.go:171","msg":"trace[683741577] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"221.896136ms","start":"2026-04-07T12:54:00.382321Z","end":"2026-04-07T12:54:00.604217Z","steps":["trace[683741577] 'process raft request' (duration: 219.428765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:00.604417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.215982ms","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-04-07T12:54:00.604493Z","caller":"traceutil/trace.go:171","msg":"trace[1487729848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:848; }","duration":"178.303935ms","start":"2026-04-07T12:54:00.426153Z","end":"2026-04-07T12:54:00.604457Z","steps":["trace[1487729848] 'agreement among raft nodes before linearized reading' (duration: 178.085059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:00.937645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.346597ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-07T12:54:00.937757Z","caller":"traceutil/trace.go:171","msg":"trace[1893114405] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"271.221039ms","start":"2026-04-07T12:54:00.666514Z","end":"2026-04-07T12:54:00.937735Z","steps":["trace[1893114405] 'read index received' (duration: 50.775148ms)","trace[1893114405] 'applied index is now lower than readState.Index' (duration: 220.44415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:54:00.938144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.610968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T12:54:00.93821Z","caller":"traceutil/trace.go:171","msg":"trace[189360089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:849; }","duration":"271.72656ms","start":"2026-04-07T12:54:00.666466Z","end":"2026-04-07T12:54:00.938192Z","steps":["trace[189360089] 'agreement among raft nodes before linearized reading' (duration: 271.466304ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:00.95134Z","caller":"traceutil/trace.go:171","msg":"trace[566394377] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"222.308485ms","start":"2026-04-07T12:54:00.729005Z","end":"2026-04-07T12:54:00.951314Z","steps":["trace[566394377] 'process raft request' (duration: 221.993787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:00.952123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.260291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050256"} {"level":"info","ts":"2026-04-07T12:54:00.952193Z","caller":"traceutil/trace.go:171","msg":"trace[793927314] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:850; }","duration":"241.363404ms","start":"2026-04-07T12:54:00.71081Z","end":"2026-04-07T12:54:00.952174Z","steps":["trace[793927314] 'agreement among raft nodes before linearized reading' (duration: 240.36777ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:01.635359Z","caller":"traceutil/trace.go:171","msg":"trace[818343684] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"108.414395ms","start":"2026-04-07T12:54:01.526925Z","end":"2026-04-07T12:54:01.63534Z","steps":["trace[818343684] 'process raft request' (duration: 108.23355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:14.955288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.745924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:54:14.955387Z","caller":"traceutil/trace.go:171","msg":"trace[1367988286] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"243.091679ms","start":"2026-04-07T12:54:14.712281Z","end":"2026-04-07T12:54:14.955372Z","steps":["trace[1367988286] 'process raft request' (duration: 82.177881ms)","trace[1367988286] 'compare' (duration: 160.625562ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:14.956007Z","caller":"traceutil/trace.go:171","msg":"trace[1346861268] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"220.042535ms","start":"2026-04-07T12:54:14.735949Z","end":"2026-04-07T12:54:14.955991Z","steps":["trace[1346861268] 'process raft request' (duration: 219.795389ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:14.956399Z","caller":"traceutil/trace.go:171","msg":"trace[1206267747] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"164.552357ms","start":"2026-04-07T12:54:14.791824Z","end":"2026-04-07T12:54:14.956376Z","steps":["trace[1206267747] 'process raft request' (duration: 164.187589ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:18.800034Z","caller":"traceutil/trace.go:171","msg":"trace[1823319544] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"112.235724ms","start":"2026-04-07T12:54:18.687763Z","end":"2026-04-07T12:54:18.799999Z","steps":["trace[1823319544] 'process raft request' (duration: 110.041521ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:19.48191Z","caller":"traceutil/trace.go:171","msg":"trace[2028199769] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"184.265833ms","start":"2026-04-07T12:54:19.297622Z","end":"2026-04-07T12:54:19.481888Z","steps":["trace[2028199769] 'process raft request' (duration: 183.087514ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:19.487381Z","caller":"traceutil/trace.go:171","msg":"trace[779771725] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1178; }","duration":"175.890719ms","start":"2026-04-07T12:54:19.311471Z","end":"2026-04-07T12:54:19.487362Z","steps":["trace[779771725] 'read index received' (duration: 169.147464ms)","trace[779771725] 'applied index is now lower than readState.Index' (duration: 6.742265ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:19.487676Z","caller":"traceutil/trace.go:171","msg":"trace[1362684788] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"184.422837ms","start":"2026-04-07T12:54:19.303241Z","end":"2026-04-07T12:54:19.487664Z","steps":["trace[1362684788] 'process raft request' (duration: 183.936816ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:19.487889Z","caller":"traceutil/trace.go:171","msg":"trace[177042415] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"162.329088ms","start":"2026-04-07T12:54:19.325541Z","end":"2026-04-07T12:54:19.48787Z","steps":["trace[177042415] 'process raft request' (duration: 161.744993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:19.487987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.466463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:19.488087Z","caller":"traceutil/trace.go:171","msg":"trace[1868022308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1145; }","duration":"176.626197ms","start":"2026-04-07T12:54:19.311439Z","end":"2026-04-07T12:54:19.488065Z","steps":["trace[1868022308] 'agreement among raft nodes before linearized reading' (duration: 176.432672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:19.488625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.48813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:19.48868Z","caller":"traceutil/trace.go:171","msg":"trace[1415679185] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:1145; }","duration":"128.572053ms","start":"2026-04-07T12:54:19.360096Z","end":"2026-04-07T12:54:19.488668Z","steps":["trace[1415679185] 'agreement among raft nodes before linearized reading' (duration: 128.480331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:19.489051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.288819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T12:54:19.489052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.123494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:19.489099Z","caller":"traceutil/trace.go:171","msg":"trace[1556979296] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1145; }","duration":"104.35073ms","start":"2026-04-07T12:54:19.384737Z","end":"2026-04-07T12:54:19.489088Z","steps":["trace[1556979296] 'agreement among raft nodes before linearized reading' (duration: 104.273628ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:19.489118Z","caller":"traceutil/trace.go:171","msg":"trace[677695155] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1145; }","duration":"104.214427ms","start":"2026-04-07T12:54:19.384884Z","end":"2026-04-07T12:54:19.489098Z","steps":["trace[677695155] 'agreement among raft nodes before linearized reading' (duration: 104.091204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:19.489329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.594706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:19.489373Z","caller":"traceutil/trace.go:171","msg":"trace[1801296599] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1145; }","duration":"104.654638ms","start":"2026-04-07T12:54:19.384704Z","end":"2026-04-07T12:54:19.489358Z","steps":["trace[1801296599] 'agreement among raft nodes before linearized reading' (duration: 104.579116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:19.489662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.898007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:19.489708Z","caller":"traceutil/trace.go:171","msg":"trace[1996660510] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1145; }","duration":"116.959739ms","start":"2026-04-07T12:54:19.372737Z","end":"2026-04-07T12:54:19.489697Z","steps":["trace[1996660510] 'agreement among raft nodes before linearized reading' (duration: 116.872957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:19.490366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.654506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T12:54:19.490391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.652345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:19.490419Z","caller":"traceutil/trace.go:171","msg":"trace[536547770] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1145; }","duration":"117.742718ms","start":"2026-04-07T12:54:19.372654Z","end":"2026-04-07T12:54:19.490397Z","steps":["trace[536547770] 'agreement among raft nodes before linearized reading' (duration: 117.668826ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:19.490424Z","caller":"traceutil/trace.go:171","msg":"trace[1100509234] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1145; }","duration":"117.699436ms","start":"2026-04-07T12:54:19.372713Z","end":"2026-04-07T12:54:19.490413Z","steps":["trace[1100509234] 'agreement among raft nodes before linearized reading' (duration: 117.655475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:19.490458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.172832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:19.490509Z","caller":"traceutil/trace.go:171","msg":"trace[418743226] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:1145; }","duration":"130.230273ms","start":"2026-04-07T12:54:19.360265Z","end":"2026-04-07T12:54:19.490496Z","steps":["trace[418743226] 'agreement among raft nodes before linearized reading' (duration: 129.916256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:19.490685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.351417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:19.490714Z","caller":"traceutil/trace.go:171","msg":"trace[1349397534] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:1145; }","duration":"130.386927ms","start":"2026-04-07T12:54:19.360319Z","end":"2026-04-07T12:54:19.490706Z","steps":["trace[1349397534] 'agreement among raft nodes before linearized reading' (duration: 130.347476ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:20.629871Z","caller":"traceutil/trace.go:171","msg":"trace[584255229] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1187; }","duration":"136.150736ms","start":"2026-04-07T12:54:20.4937Z","end":"2026-04-07T12:54:20.629851Z","steps":["trace[584255229] 'read index received' (duration: 135.963652ms)","trace[584255229] 'applied index is now lower than readState.Index' (duration: 186.174µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:20.629944Z","caller":"traceutil/trace.go:171","msg":"trace[1298113550] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"246.588854ms","start":"2026-04-07T12:54:20.383315Z","end":"2026-04-07T12:54:20.629904Z","steps":["trace[1298113550] 'process raft request' (duration: 246.40707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:20.630056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.31264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:54:20.63029Z","caller":"traceutil/trace.go:171","msg":"trace[1551631431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1152; }","duration":"136.582587ms","start":"2026-04-07T12:54:20.49368Z","end":"2026-04-07T12:54:20.630263Z","steps":["trace[1551631431] 'agreement among raft nodes before linearized reading' (duration: 136.254219ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:54:34.62091Z","caller":"traceutil/trace.go:171","msg":"trace[445911462] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"305.826367ms","start":"2026-04-07T12:54:34.315058Z","end":"2026-04-07T12:54:34.620885Z","steps":["trace[445911462] 'read index received' (duration: 305.652993ms)","trace[445911462] 'applied index is now lower than readState.Index' (duration: 172.374µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:54:34.621074Z","caller":"traceutil/trace.go:171","msg":"trace[270346827] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"306.79538ms","start":"2026-04-07T12:54:34.314229Z","end":"2026-04-07T12:54:34.621024Z","steps":["trace[270346827] 'process raft request' (duration: 306.508434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:34.62123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.151766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:34.621317Z","caller":"traceutil/trace.go:171","msg":"trace[582521080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1229; }","duration":"306.293169ms","start":"2026-04-07T12:54:34.315002Z","end":"2026-04-07T12:54:34.621296Z","steps":["trace[582521080] 'agreement among raft nodes before linearized reading' (duration: 306.036393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:34.621362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:34.314975Z","time spent":"306.37785ms","remote":"127.0.0.1:56982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:54:34.621393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.014309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-07T12:54:34.621501Z","caller":"traceutil/trace.go:171","msg":"trace[1035208045] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1229; }","duration":"126.133372ms","start":"2026-04-07T12:54:34.495328Z","end":"2026-04-07T12:54:34.621461Z","steps":["trace[1035208045] 'agreement among raft nodes before linearized reading' (duration: 125.764873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:54:34.621465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:54:34.314217Z","time spent":"307.137158ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:54:34.621821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.689091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:54:34.621867Z","caller":"traceutil/trace.go:171","msg":"trace[1569694843] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1229; }","duration":"192.765013ms","start":"2026-04-07T12:54:34.429086Z","end":"2026-04-07T12:54:34.621851Z","steps":["trace[1569694843] 'agreement among raft nodes before linearized reading' (duration: 191.946104ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:09.100306Z","caller":"traceutil/trace.go:171","msg":"trace[122949234] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"141.990197ms","start":"2026-04-07T12:55:08.958294Z","end":"2026-04-07T12:55:09.100284Z","steps":["trace[122949234] 'process raft request' (duration: 141.853934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:15.037701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.603547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T12:55:15.037773Z","caller":"traceutil/trace.go:171","msg":"trace[118626538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1794; }","duration":"127.737021ms","start":"2026-04-07T12:55:14.910021Z","end":"2026-04-07T12:55:15.037758Z","steps":["trace[118626538] 'range keys from in-memory index tree' (duration: 127.377481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:15.037935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.610176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T12:55:15.037958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.799021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:55:15.038072Z","caller":"traceutil/trace.go:171","msg":"trace[2101198645] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1794; }","duration":"134.804881ms","start":"2026-04-07T12:55:14.903213Z","end":"2026-04-07T12:55:15.038018Z","steps":["trace[2101198645] 'count revisions from in-memory index tree' (duration: 134.491164ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:15.038075Z","caller":"traceutil/trace.go:171","msg":"trace[1832074251] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1794; }","duration":"134.955085ms","start":"2026-04-07T12:55:14.903083Z","end":"2026-04-07T12:55:15.038038Z","steps":["trace[1832074251] 'range keys from in-memory index tree' (duration: 134.716079ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:15.040634Z","caller":"traceutil/trace.go:171","msg":"trace[934989341] linearizableReadLoop","detail":"{readStateIndex:1868; appliedIndex:1867; }","duration":"109.855617ms","start":"2026-04-07T12:55:14.930763Z","end":"2026-04-07T12:55:15.040618Z","steps":["trace[934989341] 'read index received' (duration: 109.637432ms)","trace[934989341] 'applied index is now lower than readState.Index' (duration: 217.205µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:15.040803Z","caller":"traceutil/trace.go:171","msg":"trace[1854591912] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"115.77557ms","start":"2026-04-07T12:55:14.925016Z","end":"2026-04-07T12:55:15.040792Z","steps":["trace[1854591912] 'process raft request' (duration: 115.429052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:15.04085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.070563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:55:15.040887Z","caller":"traceutil/trace.go:171","msg":"trace[235839226] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1795; }","duration":"110.164125ms","start":"2026-04-07T12:55:14.930712Z","end":"2026-04-07T12:55:15.040876Z","steps":["trace[235839226] 'agreement among raft nodes before linearized reading' (duration: 110.084543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:15.040993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.091802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:55:15.041031Z","caller":"traceutil/trace.go:171","msg":"trace[1336017809] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1795; }","duration":"110.171134ms","start":"2026-04-07T12:55:14.93085Z","end":"2026-04-07T12:55:15.041022Z","steps":["trace[1336017809] 'agreement among raft nodes before linearized reading' (duration: 110.113613ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:23.469669Z","caller":"traceutil/trace.go:171","msg":"trace[458639422] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1980; }","duration":"287.471948ms","start":"2026-04-07T12:55:23.182177Z","end":"2026-04-07T12:55:23.469649Z","steps":["trace[458639422] 'read index received' (duration: 286.107775ms)","trace[458639422] 'applied index is now lower than readState.Index' (duration: 1.363063ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:23.469777Z","caller":"traceutil/trace.go:171","msg":"trace[1510998641] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"294.049688ms","start":"2026-04-07T12:55:23.175717Z","end":"2026-04-07T12:55:23.469767Z","steps":["trace[1510998641] 'process raft request' (duration: 292.673575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:23.469986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.799017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T12:55:23.470021Z","caller":"traceutil/trace.go:171","msg":"trace[1462152873] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1906; }","duration":"287.877318ms","start":"2026-04-07T12:55:23.182135Z","end":"2026-04-07T12:55:23.470013Z","steps":["trace[1462152873] 'agreement among raft nodes before linearized reading' (duration: 287.747575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:23.470153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.088563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T12:55:23.470218Z","caller":"traceutil/trace.go:171","msg":"trace[222743093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1906; }","duration":"278.192605ms","start":"2026-04-07T12:55:23.192011Z","end":"2026-04-07T12:55:23.470204Z","steps":["trace[222743093] 'agreement among raft nodes before linearized reading' (duration: 277.935258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:23.470368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.80511ms","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-04-07T12:55:23.470401Z","caller":"traceutil/trace.go:171","msg":"trace[440035028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1906; }","duration":"115.871851ms","start":"2026-04-07T12:55:23.354519Z","end":"2026-04-07T12:55:23.470391Z","steps":["trace[440035028] 'agreement among raft nodes before linearized reading' (duration: 115.779448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:23.470421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.456787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:55:23.470503Z","caller":"traceutil/trace.go:171","msg":"trace[387517721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1906; }","duration":"157.59647ms","start":"2026-04-07T12:55:23.312889Z","end":"2026-04-07T12:55:23.470486Z","steps":["trace[387517721] 'agreement among raft nodes before linearized reading' (duration: 157.466647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:25.034397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198695912483819819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T12:55:25.535275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198695912483819819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T12:55:25.767821Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.589675191s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T12:55:25.768357Z","caller":"traceutil/trace.go:171","msg":"trace[1220637215] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"1.590285086s","start":"2026-04-07T12:55:24.178039Z","end":"2026-04-07T12:55:25.768324Z","steps":["trace[1220637215] 'process raft request' (duration: 1.5900483s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:25.768546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:24.178Z","time spent":"1.59044594s","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:55:26.773829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.108697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:55:26.774368Z","caller":"traceutil/trace.go:171","msg":"trace[1973705372] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"2.197905547s","start":"2026-04-07T12:55:24.576429Z","end":"2026-04-07T12:55:26.774334Z","steps":["trace[1973705372] 'process raft request' (duration: 2.197534928s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.774516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:24.5764Z","time spent":"2.198034991s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:26.774571Z","caller":"traceutil/trace.go:171","msg":"trace[2042898649] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"1.627584204s","start":"2026-04-07T12:55:25.146959Z","end":"2026-04-07T12:55:26.774543Z","steps":["trace[2042898649] 'process raft request' (duration: 1.627170473s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.774711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.146936Z","time spent":"1.627698477s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:26.774744Z","caller":"traceutil/trace.go:171","msg":"trace[301208232] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"1.908394941s","start":"2026-04-07T12:55:24.866315Z","end":"2026-04-07T12:55:26.77471Z","steps":["trace[301208232] 'process raft request' (duration: 1.907763935s)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:26.774789Z","caller":"traceutil/trace.go:171","msg":"trace[132855295] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"2.408427266s","start":"2026-04-07T12:55:24.366323Z","end":"2026-04-07T12:55:26.774751Z","steps":["trace[132855295] 'process raft request' (duration: 2.154194421s)","trace[132855295] 'compare' (duration: 252.79699ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:55:26.774879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:24.366291Z","time spent":"2.408540039s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:55:26.774908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:24.866277Z","time spent":"1.908535894s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:26.775103Z","caller":"traceutil/trace.go:171","msg":"trace[1986473054] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1992; }","duration":"2.241142053s","start":"2026-04-07T12:55:24.533943Z","end":"2026-04-07T12:55:26.775085Z","steps":["trace[1986473054] 'read index received' (duration: 1.234151577s)","trace[1986473054] 'applied index is now lower than readState.Index' (duration: 1.006988456s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:55:26.790484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.256527385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-07T12:55:26.790617Z","caller":"traceutil/trace.go:171","msg":"trace[524903616] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1922; }","duration":"2.256692739s","start":"2026-04-07T12:55:24.533886Z","end":"2026-04-07T12:55:26.790579Z","steps":["trace[524903616] 'agreement among raft nodes before linearized reading' (duration: 2.241266496s)","trace[524903616] 'range keys from in-memory index tree' (duration: 15.180067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:55:26.790714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:24.53386Z","time spent":"2.256821931s","remote":"127.0.0.1:58144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T12:55:26.790798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70229853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6afcc113-bfa6-4e5b-a190-511e829f8a66\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-04-07T12:55:26.790863Z","caller":"traceutil/trace.go:171","msg":"trace[702325942] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6afcc113-bfa6-4e5b-a190-511e829f8a66; range_end:; response_count:1; response_revision:1923; }","duration":"1.702410932s","start":"2026-04-07T12:55:25.088434Z","end":"2026-04-07T12:55:26.790845Z","steps":["trace[702325942] 'agreement among raft nodes before linearized reading' (duration: 1.702212578s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.790905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.088412Z","time spent":"1.702482423s","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3767,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6afcc113-bfa6-4e5b-a190-511e829f8a66\" "} {"level":"info","ts":"2026-04-07T12:55:26.791148Z","caller":"traceutil/trace.go:171","msg":"trace[1933426558] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"643.127271ms","start":"2026-04-07T12:55:26.148006Z","end":"2026-04-07T12:55:26.791133Z","steps":["trace[1933426558] 'process raft request' (duration: 642.467145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.79124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:26.14797Z","time spent":"643.210634ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:55:26.79125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309104626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T12:55:26.791289Z","caller":"traceutil/trace.go:171","msg":"trace[611312916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1923; }","duration":"1.309145477s","start":"2026-04-07T12:55:25.482133Z","end":"2026-04-07T12:55:26.791278Z","steps":["trace[611312916] 'agreement among raft nodes before linearized reading' (duration: 1.309030484s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.791319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.482007Z","time spent":"1.309305402s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T12:55:26.791505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.306962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"warn","ts":"2026-04-07T12:55:26.791513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.639502722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:55:26.791565Z","caller":"traceutil/trace.go:171","msg":"trace[56330970] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1923; }","duration":"1.639590954s","start":"2026-04-07T12:55:25.151961Z","end":"2026-04-07T12:55:26.791552Z","steps":["trace[56330970] 'agreement among raft nodes before linearized reading' (duration: 1.639489061s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.792182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.151855Z","time spent":"1.64028439s","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":23,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-07T12:55:26.791573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01876842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:55:26.792399Z","caller":"traceutil/trace.go:171","msg":"trace[1226952549] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1923; }","duration":"1.01963888s","start":"2026-04-07T12:55:25.772742Z","end":"2026-04-07T12:55:26.792381Z","steps":["trace[1226952549] 'agreement among raft nodes before linearized reading' (duration: 1.018808301s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.792981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.772728Z","time spent":"1.019702642s","remote":"127.0.0.1:57158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-07T12:55:26.791631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134149749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:55:26.793552Z","caller":"traceutil/trace.go:171","msg":"trace[764705919] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1923; }","duration":"1.136080705s","start":"2026-04-07T12:55:25.657427Z","end":"2026-04-07T12:55:26.793507Z","steps":["trace[764705919] 'agreement among raft nodes before linearized reading' (duration: 1.134163549s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.791627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627519723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-07T12:55:26.793687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.657406Z","time spent":"1.136230079s","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":13,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-07T12:55:26.791711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26333542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T12:55:26.793785Z","caller":"traceutil/trace.go:171","msg":"trace[543719271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1923; }","duration":"1.265441311s","start":"2026-04-07T12:55:25.528333Z","end":"2026-04-07T12:55:26.793775Z","steps":["trace[543719271] 'agreement among raft nodes before linearized reading' (duration: 1.26331308s)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:26.793741Z","caller":"traceutil/trace.go:171","msg":"trace[1094138401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1923; }","duration":"1.629679095s","start":"2026-04-07T12:55:25.164037Z","end":"2026-04-07T12:55:26.793716Z","steps":["trace[1094138401] 'agreement among raft nodes before linearized reading' (duration: 1.62737705s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.793931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.164016Z","time spent":"1.629871579s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T12:55:26.79382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.528318Z","time spent":"1.265494002s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T12:55:26.791777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479485495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:55:26.794186Z","caller":"traceutil/trace.go:171","msg":"trace[116385211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1923; }","duration":"1.481920924s","start":"2026-04-07T12:55:25.312249Z","end":"2026-04-07T12:55:26.79417Z","steps":["trace[116385211] 'agreement among raft nodes before linearized reading' (duration: 1.479507246s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.794227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.312229Z","time spent":"1.481985735s","remote":"127.0.0.1:56988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:55:26.791778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309637619s","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-04-07T12:55:26.794378Z","caller":"traceutil/trace.go:171","msg":"trace[796381787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1923; }","duration":"1.312276833s","start":"2026-04-07T12:55:25.48209Z","end":"2026-04-07T12:55:26.794367Z","steps":["trace[796381787] 'agreement among raft nodes before linearized reading' (duration: 1.309595658s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.794443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.482003Z","time spent":"1.312429397s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T12:55:26.791844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26373449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T12:55:26.794561Z","caller":"traceutil/trace.go:171","msg":"trace[362919838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1923; }","duration":"1.266501078s","start":"2026-04-07T12:55:25.528039Z","end":"2026-04-07T12:55:26.79454Z","steps":["trace[362919838] 'agreement among raft nodes before linearized reading' (duration: 1.26370345s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.794624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.528017Z","time spent":"1.2665913s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T12:55:26.791533Z","caller":"traceutil/trace.go:171","msg":"trace[1940653212] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1923; }","duration":"625.373333ms","start":"2026-04-07T12:55:26.166152Z","end":"2026-04-07T12:55:26.791525Z","steps":["trace[1940653212] 'agreement among raft nodes before linearized reading' (duration: 625.288171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.794849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:26.16613Z","time spent":"628.710083ms","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":10351,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-07T12:55:26.791704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.701730636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:55:26.795014Z","caller":"traceutil/trace.go:171","msg":"trace[1675255212] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1923; }","duration":"1.705076247s","start":"2026-04-07T12:55:25.089923Z","end":"2026-04-07T12:55:26.794999Z","steps":["trace[1675255212] 'agreement among raft nodes before linearized reading' (duration: 1.701718326s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:26.79505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:25.089914Z","time spent":"1.705125998s","remote":"127.0.0.1:55194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-07T12:55:38.17888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.115451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-07T12:55:38.178959Z","caller":"traceutil/trace.go:171","msg":"trace[1899970058] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2031; }","duration":"311.229664ms","start":"2026-04-07T12:55:37.867705Z","end":"2026-04-07T12:55:38.178935Z","steps":["trace[1899970058] 'range keys from in-memory index tree' (duration: 310.954467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:38.178985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:37.867686Z","time spent":"311.293725ms","remote":"127.0.0.1:58144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-07T12:55:38.977549Z","caller":"traceutil/trace.go:171","msg":"trace[1852512517] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"156.043667ms","start":"2026-04-07T12:55:38.821477Z","end":"2026-04-07T12:55:38.97752Z","steps":["trace[1852512517] 'process raft request' (duration: 119.805402ms)","trace[1852512517] 'compare' (duration: 36.066861ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:38.977923Z","caller":"traceutil/trace.go:171","msg":"trace[860199085] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"147.762297ms","start":"2026-04-07T12:55:38.830139Z","end":"2026-04-07T12:55:38.977902Z","steps":["trace[860199085] 'process raft request' (duration: 147.336927ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:38.977945Z","caller":"traceutil/trace.go:171","msg":"trace[1653038295] linearizableReadLoop","detail":"{readStateIndex:2113; appliedIndex:2110; }","duration":"119.283299ms","start":"2026-04-07T12:55:38.85852Z","end":"2026-04-07T12:55:38.977803Z","steps":["trace[1653038295] 'read index received' (duration: 82.753567ms)","trace[1653038295] 'applied index is now lower than readState.Index' (duration: 36.528462ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:38.978357Z","caller":"traceutil/trace.go:171","msg":"trace[1500771483] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"148.009824ms","start":"2026-04-07T12:55:38.830329Z","end":"2026-04-07T12:55:38.978338Z","steps":["trace[1500771483] 'process raft request' (duration: 147.348847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:38.978578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.048138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T12:55:38.97865Z","caller":"traceutil/trace.go:171","msg":"trace[1057824676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2035; }","duration":"120.156951ms","start":"2026-04-07T12:55:38.858477Z","end":"2026-04-07T12:55:38.978633Z","steps":["trace[1057824676] 'agreement among raft nodes before linearized reading' (duration: 119.787792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:38.978931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.476783ms","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-04-07T12:55:38.979043Z","caller":"traceutil/trace.go:171","msg":"trace[1328770884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2035; }","duration":"119.637038ms","start":"2026-04-07T12:55:38.859394Z","end":"2026-04-07T12:55:38.979031Z","steps":["trace[1328770884] 'agreement among raft nodes before linearized reading' (duration: 119.30394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:38.97892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.624303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T12:55:38.97932Z","caller":"traceutil/trace.go:171","msg":"trace[2083901614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2035; }","duration":"114.011762ms","start":"2026-04-07T12:55:38.865267Z","end":"2026-04-07T12:55:38.979278Z","steps":["trace[2083901614] 'agreement among raft nodes before linearized reading' (duration: 113.5105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:38.979049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.552679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T12:55:38.979627Z","caller":"traceutil/trace.go:171","msg":"trace[1771936309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2035; }","duration":"116.145334ms","start":"2026-04-07T12:55:38.863443Z","end":"2026-04-07T12:55:38.979588Z","steps":["trace[1771936309] 'agreement among raft nodes before linearized reading' (duration: 115.458717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:38.981088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.714533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:55:38.981212Z","caller":"traceutil/trace.go:171","msg":"trace[602595623] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2035; }","duration":"110.834795ms","start":"2026-04-07T12:55:38.870346Z","end":"2026-04-07T12:55:38.981181Z","steps":["trace[602595623] 'agreement among raft nodes before linearized reading' (duration: 108.607571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:43.501216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198695912483820102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T12:55:44.00225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198695912483820102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T12:55:44.20096Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.21131704s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T12:55:44.202588Z","caller":"traceutil/trace.go:171","msg":"trace[659358047] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"1.213084692s","start":"2026-04-07T12:55:42.98948Z","end":"2026-04-07T12:55:44.202565Z","steps":["trace[659358047] 'process raft request' (duration: 1.211657418s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:44.202724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:42.98945Z","time spent":"1.213209335s","remote":"127.0.0.1:57238","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-04-07T12:55:44.203657Z","caller":"traceutil/trace.go:171","msg":"trace[1314172927] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"1.210439168s","start":"2026-04-07T12:55:42.993194Z","end":"2026-04-07T12:55:44.203633Z","steps":["trace[1314172927] 'process raft request' (duration: 1.210307455s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:44.203815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:42.993175Z","time spent":"1.210566932s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:55:44.204537Z","caller":"traceutil/trace.go:171","msg":"trace[107243102] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"1.211238268s","start":"2026-04-07T12:55:42.993267Z","end":"2026-04-07T12:55:44.204505Z","steps":["trace[107243102] 'process raft request' (duration: 1.210329997s)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:44.204577Z","caller":"traceutil/trace.go:171","msg":"trace[1018105728] linearizableReadLoop","detail":"{readStateIndex:2137; appliedIndex:2133; }","duration":"1.204429323s","start":"2026-04-07T12:55:43.000091Z","end":"2026-04-07T12:55:44.204521Z","steps":["trace[1018105728] 'read index received' (duration: 1.201294858s)","trace[1018105728] 'applied index is now lower than readState.Index' (duration: 3.133425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:55:44.204668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:42.993237Z","time spent":"1.21136033s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:55:44.204921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196275287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T12:55:44.205004Z","caller":"traceutil/trace.go:171","msg":"trace[700957248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2058; }","duration":"1.19639447s","start":"2026-04-07T12:55:43.008592Z","end":"2026-04-07T12:55:44.204986Z","steps":["trace[700957248] 'agreement among raft nodes before linearized reading' (duration: 1.196157834s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:44.205063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.066909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-07T12:55:44.205089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19185787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T12:55:44.205136Z","caller":"traceutil/trace.go:171","msg":"trace[1325985495] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2058; }","duration":"992.167342ms","start":"2026-04-07T12:55:43.212942Z","end":"2026-04-07T12:55:44.205109Z","steps":["trace[1325985495] 'agreement among raft nodes before linearized reading' (duration: 991.846854ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:44.205154Z","caller":"traceutil/trace.go:171","msg":"trace[1981354558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2058; }","duration":"1.191944272s","start":"2026-04-07T12:55:43.013197Z","end":"2026-04-07T12:55:44.205141Z","steps":["trace[1981354558] 'agreement among raft nodes before linearized reading' (duration: 1.191752777s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:44.205173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194811262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-07T12:55:44.205201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:43.013186Z","time spent":"1.192001494s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T12:55:44.205205Z","caller":"traceutil/trace.go:171","msg":"trace[610085262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2058; }","duration":"1.194862123s","start":"2026-04-07T12:55:43.010334Z","end":"2026-04-07T12:55:44.205196Z","steps":["trace[610085262] 'agreement among raft nodes before linearized reading' (duration: 1.19475338s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:44.205238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:43.010313Z","time spent":"1.194919175s","remote":"127.0.0.1:57238","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":"info","ts":"2026-04-07T12:55:44.205353Z","caller":"traceutil/trace.go:171","msg":"trace[75346606] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"398.426824ms","start":"2026-04-07T12:55:43.806916Z","end":"2026-04-07T12:55:44.205342Z","steps":["trace[75346606] 'process raft request' (duration: 397.539103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:44.20536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190885347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T12:55:44.205377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205280174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T12:55:44.205396Z","caller":"traceutil/trace.go:171","msg":"trace[1236875297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2058; }","duration":"1.190964788s","start":"2026-04-07T12:55:43.014421Z","end":"2026-04-07T12:55:44.205386Z","steps":["trace[1236875297] 'agreement among raft nodes before linearized reading' (duration: 1.190877686s)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:44.205428Z","caller":"traceutil/trace.go:171","msg":"trace[2080150792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2058; }","duration":"1.205347445s","start":"2026-04-07T12:55:43.000072Z","end":"2026-04-07T12:55:44.205419Z","steps":["trace[2080150792] 'agreement among raft nodes before linearized reading' (duration: 1.205225442s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:44.205437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:43.014391Z","time spent":"1.19103979s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T12:55:44.205453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:43.000063Z","time spent":"1.205384327s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T12:55:44.205459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:43.806894Z","time spent":"398.489495ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:55:44.205062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:43.008581Z","time spent":"1.196467251s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T12:55:44.205237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:43.212927Z","time spent":"992.253064ms","remote":"127.0.0.1:58144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T12:55:44.205724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.283249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:55:44.206703Z","caller":"traceutil/trace.go:171","msg":"trace[1125221704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2058; }","duration":"895.271274ms","start":"2026-04-07T12:55:43.311421Z","end":"2026-04-07T12:55:44.206692Z","steps":["trace[1125221704] 'agreement among raft nodes before linearized reading' (duration: 893.907791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:44.206741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:55:43.31136Z","time spent":"895.371046ms","remote":"127.0.0.1:56988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:55:56.378261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.610636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a4145efaa98948\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-07T12:55:56.378536Z","caller":"traceutil/trace.go:171","msg":"trace[346519099] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a4145efaa98948; range_end:; response_count:1; response_revision:2125; }","duration":"129.917173ms","start":"2026-04-07T12:55:56.248592Z","end":"2026-04-07T12:55:56.378509Z","steps":["trace[346519099] 'agreement among raft nodes before linearized reading' (duration: 43.019698ms)","trace[346519099] 'range keys from in-memory index tree' (duration: 86.074096ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:56.549407Z","caller":"traceutil/trace.go:171","msg":"trace[942273793] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2209; }","duration":"160.331895ms","start":"2026-04-07T12:55:56.389031Z","end":"2026-04-07T12:55:56.549363Z","steps":["trace[942273793] 'read index received' (duration: 2.300585ms)","trace[942273793] 'applied index is now lower than readState.Index' (duration: 158.03048ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:55:56.54951Z","caller":"traceutil/trace.go:171","msg":"trace[1805112092] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"165.311385ms","start":"2026-04-07T12:55:56.384183Z","end":"2026-04-07T12:55:56.549495Z","steps":["trace[1805112092] 'process raft request' (duration: 120.426183ms)","trace[1805112092] 'compare' (duration: 44.598596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:55:56.549691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.583673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T12:55:56.549746Z","caller":"traceutil/trace.go:171","msg":"trace[1929886003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2130; }","duration":"158.664575ms","start":"2026-04-07T12:55:56.391071Z","end":"2026-04-07T12:55:56.549736Z","steps":["trace[1929886003] 'agreement among raft nodes before linearized reading' (duration: 158.512671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:56.549867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.676082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T12:55:56.549899Z","caller":"traceutil/trace.go:171","msg":"trace[1629850589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2130; }","duration":"157.719052ms","start":"2026-04-07T12:55:56.392167Z","end":"2026-04-07T12:55:56.549886Z","steps":["trace[1629850589] 'agreement among raft nodes before linearized reading' (duration: 157.643681ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:55:56.550048Z","caller":"traceutil/trace.go:171","msg":"trace[1701974161] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"159.341822ms","start":"2026-04-07T12:55:56.390675Z","end":"2026-04-07T12:55:56.550017Z","steps":["trace[1701974161] 'process raft request' (duration: 158.615084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:56.550064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.726917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:55:56.550091Z","caller":"traceutil/trace.go:171","msg":"trace[464939187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2130; }","duration":"158.767958ms","start":"2026-04-07T12:55:56.391315Z","end":"2026-04-07T12:55:56.550083Z","steps":["trace[464939187] 'agreement among raft nodes before linearized reading' (duration: 158.661606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:56.550155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.791009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T12:55:56.550241Z","caller":"traceutil/trace.go:171","msg":"trace[1729654992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2130; }","duration":"158.896831ms","start":"2026-04-07T12:55:56.391326Z","end":"2026-04-07T12:55:56.550223Z","steps":["trace[1729654992] 'agreement among raft nodes before linearized reading' (duration: 158.638715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:55:56.551012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.969414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-07T12:55:56.551084Z","caller":"traceutil/trace.go:171","msg":"trace[33100881] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2130; }","duration":"162.067266ms","start":"2026-04-07T12:55:56.388999Z","end":"2026-04-07T12:55:56.551066Z","steps":["trace[33100881] 'agreement among raft nodes before linearized reading' (duration: 160.516389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:04.726573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.091449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:56:04.726691Z","caller":"traceutil/trace.go:171","msg":"trace[2052837329] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2170; }","duration":"182.244893ms","start":"2026-04-07T12:56:04.544415Z","end":"2026-04-07T12:56:04.72666Z","steps":["trace[2052837329] 'count revisions from in-memory index tree' (duration: 181.966154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:04.726759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.244506ms","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-04-07T12:56:04.726788Z","caller":"traceutil/trace.go:171","msg":"trace[1901959377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2170; }","duration":"144.273017ms","start":"2026-04-07T12:56:04.582507Z","end":"2026-04-07T12:56:04.72678Z","steps":["trace[1901959377] 'range keys from in-memory index tree' (duration: 144.155574ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:06.524196Z","caller":"traceutil/trace.go:171","msg":"trace[104725900] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2261; }","duration":"212.791797ms","start":"2026-04-07T12:56:06.311379Z","end":"2026-04-07T12:56:06.52417Z","steps":["trace[104725900] 'read index received' (duration: 212.720896ms)","trace[104725900] 'applied index is now lower than readState.Index' (duration: 66.021µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:56:06.524625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.208828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:56:06.524774Z","caller":"traceutil/trace.go:171","msg":"trace[340281766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2177; }","duration":"213.377702ms","start":"2026-04-07T12:56:06.311334Z","end":"2026-04-07T12:56:06.524712Z","steps":["trace[340281766] 'agreement among raft nodes before linearized reading' (duration: 212.961991ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:06.964301Z","caller":"traceutil/trace.go:171","msg":"trace[34063350] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"633.517736ms","start":"2026-04-07T12:56:06.330753Z","end":"2026-04-07T12:56:06.96427Z","steps":["trace[34063350] 'process raft request' (duration: 633.279361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:06.964534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.330714Z","time spent":"633.742362ms","remote":"127.0.0.1:57238","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-04-07T12:56:06.964661Z","caller":"traceutil/trace.go:171","msg":"trace[1415284938] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"481.165646ms","start":"2026-04-07T12:56:06.483466Z","end":"2026-04-07T12:56:06.964632Z","steps":["trace[1415284938] 'process raft request' (duration: 480.733086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:06.964779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.48344Z","time spent":"481.277808ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:56:06.964975Z","caller":"traceutil/trace.go:171","msg":"trace[1233658809] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2262; }","duration":"440.70437ms","start":"2026-04-07T12:56:06.524257Z","end":"2026-04-07T12:56:06.964961Z","steps":["trace[1233658809] 'read index received' (duration: 439.794959ms)","trace[1233658809] 'applied index is now lower than readState.Index' (duration: 908.601µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:56:06.965022Z","caller":"traceutil/trace.go:171","msg":"trace[359298794] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"462.71286ms","start":"2026-04-07T12:56:06.5023Z","end":"2026-04-07T12:56:06.965013Z","steps":["trace[359298794] 'process raft request' (duration: 462.171417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:06.965067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.502271Z","time spent":"462.772481ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:56:06.965163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.211753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-07T12:56:06.965194Z","caller":"traceutil/trace.go:171","msg":"trace[332978274] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2180; }","duration":"456.307806ms","start":"2026-04-07T12:56:06.508877Z","end":"2026-04-07T12:56:06.965184Z","steps":["trace[332978274] 'agreement among raft nodes before linearized reading' (duration: 456.159462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:06.965216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.508833Z","time spent":"456.377907ms","remote":"127.0.0.1:58144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-07T12:56:07.205491Z","caller":"traceutil/trace.go:171","msg":"trace[667225227] linearizableReadLoop","detail":"{readStateIndex:2268; appliedIndex:2265; }","duration":"240.448734ms","start":"2026-04-07T12:56:06.96502Z","end":"2026-04-07T12:56:07.205468Z","steps":["trace[667225227] 'read index received' (duration: 240.224729ms)","trace[667225227] 'applied index is now lower than readState.Index' (duration: 222.985µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:56:07.205886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.873499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T12:56:07.205966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.15481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:56:07.206124Z","caller":"traceutil/trace.go:171","msg":"trace[1344232647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2183; }","duration":"463.353446ms","start":"2026-04-07T12:56:06.742752Z","end":"2026-04-07T12:56:07.206105Z","steps":["trace[1344232647] 'agreement among raft nodes before linearized reading' (duration: 463.09806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:07.205887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.416938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T12:56:07.206159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.184688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-07T12:56:07.206184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.742726Z","time spent":"463.443917ms","remote":"127.0.0.1:57238","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-04-07T12:56:07.206197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.869284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T12:56:07.206219Z","caller":"traceutil/trace.go:171","msg":"trace[1769768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2183; }","duration":"445.263739ms","start":"2026-04-07T12:56:06.760941Z","end":"2026-04-07T12:56:07.206205Z","steps":["trace[1769768] 'agreement among raft nodes before linearized reading' (duration: 445.037734ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:07.206004Z","caller":"traceutil/trace.go:171","msg":"trace[1464221710] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"431.547069ms","start":"2026-04-07T12:56:06.774427Z","end":"2026-04-07T12:56:07.205974Z","steps":["trace[1464221710] 'process raft request' (duration: 430.996516ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:07.206206Z","caller":"traceutil/trace.go:171","msg":"trace[803001293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2183; }","duration":"444.805698ms","start":"2026-04-07T12:56:06.761387Z","end":"2026-04-07T12:56:07.206193Z","steps":["trace[803001293] 'agreement among raft nodes before linearized reading' (duration: 444.119192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:07.206264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.760935Z","time spent":"445.319621ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T12:56:07.206293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.761381Z","time spent":"444.902861ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T12:56:07.205927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.020585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T12:56:07.206379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.774414Z","time spent":"431.869047ms","remote":"127.0.0.1:57158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:56:07.206017Z","caller":"traceutil/trace.go:171","msg":"trace[1594414038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2183; }","duration":"445.028104ms","start":"2026-04-07T12:56:06.760967Z","end":"2026-04-07T12:56:07.205995Z","steps":["trace[1594414038] 'agreement among raft nodes before linearized reading' (duration: 444.735567ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:07.206419Z","caller":"traceutil/trace.go:171","msg":"trace[912918089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2183; }","duration":"680.535598ms","start":"2026-04-07T12:56:06.525848Z","end":"2026-04-07T12:56:07.206383Z","steps":["trace[912918089] 'agreement among raft nodes before linearized reading' (duration: 680.025075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:07.206509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.525824Z","time spent":"680.658692ms","remote":"127.0.0.1:56982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T12:56:07.206246Z","caller":"traceutil/trace.go:171","msg":"trace[1515211006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2183; }","duration":"445.938296ms","start":"2026-04-07T12:56:06.760295Z","end":"2026-04-07T12:56:07.206234Z","steps":["trace[1515211006] 'agreement among raft nodes before linearized reading' (duration: 445.680709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:07.20653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.760964Z","time spent":"445.546787ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T12:56:07.206616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.760284Z","time spent":"446.291934ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T12:56:07.205585Z","caller":"traceutil/trace.go:171","msg":"trace[1145596110] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"473.627252ms","start":"2026-04-07T12:56:06.731929Z","end":"2026-04-07T12:56:07.205556Z","steps":["trace[1145596110] 'process raft request' (duration: 473.432467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:07.206752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.7319Z","time spent":"474.76859ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:56:07.205567Z","caller":"traceutil/trace.go:171","msg":"trace[1421509227] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"679.550575ms","start":"2026-04-07T12:56:06.525964Z","end":"2026-04-07T12:56:07.205515Z","steps":["trace[1421509227] 'process raft request' (duration: 679.212127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:07.206875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:06.52595Z","time spent":"680.867177ms","remote":"127.0.0.1:57048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:56:07.236158Z","caller":"traceutil/trace.go:171","msg":"trace[2137690724] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"243.936108ms","start":"2026-04-07T12:56:06.992196Z","end":"2026-04-07T12:56:07.236132Z","steps":["trace[2137690724] 'process raft request' (duration: 243.811265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:07.236628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.865866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-04-07T12:56:07.236745Z","caller":"traceutil/trace.go:171","msg":"trace[1369345429] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2184; }","duration":"234.026579ms","start":"2026-04-07T12:56:07.002701Z","end":"2026-04-07T12:56:07.236728Z","steps":["trace[1369345429] 'agreement among raft nodes before linearized reading' (duration: 233.556548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:08.274619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.648778Z","time spent":"625.8335ms","remote":"127.0.0.1:60130","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T12:56:08.27516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.368734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:56:08.275415Z","caller":"traceutil/trace.go:171","msg":"trace[285746807] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"1.062162303s","start":"2026-04-07T12:56:07.213235Z","end":"2026-04-07T12:56:08.275397Z","steps":["trace[285746807] 'process raft request' (duration: 1.062075701s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:08.275488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.213213Z","time spent":"1.062242775s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:56:08.275584Z","caller":"traceutil/trace.go:171","msg":"trace[1798308880] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"1.062400349s","start":"2026-04-07T12:56:07.213173Z","end":"2026-04-07T12:56:08.275573Z","steps":["trace[1798308880] 'process raft request' (duration: 1.062050271s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:08.275646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.213112Z","time spent":"1.062499561s","remote":"127.0.0.1:57238","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-04-07T12:56:08.275719Z","caller":"traceutil/trace.go:171","msg":"trace[19211034] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"1.062642405s","start":"2026-04-07T12:56:07.21305Z","end":"2026-04-07T12:56:08.275692Z","steps":["trace[19211034] 'process raft request' (duration: 110.647076ms)","trace[19211034] 'compare' (duration: 951.081137ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:56:08.275994Z","caller":"traceutil/trace.go:171","msg":"trace[741471948] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"1.062691236s","start":"2026-04-07T12:56:07.213291Z","end":"2026-04-07T12:56:08.275982Z","steps":["trace[741471948] 'process raft request' (duration: 1.0620596s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:08.275968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.213015Z","time spent":"1.06283398s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:56:08.27606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.213263Z","time spent":"1.062765418s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:56:08.276325Z","caller":"traceutil/trace.go:171","msg":"trace[2085671198] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2269; }","duration":"1.030674955s","start":"2026-04-07T12:56:07.245638Z","end":"2026-04-07T12:56:08.276313Z","steps":["trace[2085671198] 'read index received' (duration: 77.997969ms)","trace[2085671198] 'applied index is now lower than readState.Index' (duration: 952.676085ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:56:08.276884Z","caller":"traceutil/trace.go:171","msg":"trace[425989053] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"1.063435484s","start":"2026-04-07T12:56:07.213431Z","end":"2026-04-07T12:56:08.276866Z","steps":["trace[425989053] 'process raft request' (duration: 1.062506372s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:08.276903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031247928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T12:56:08.276988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.213424Z","time spent":"1.063502155s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:56:08.277003Z","caller":"traceutil/trace.go:171","msg":"trace[432645070] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2190; }","duration":"1.031381301s","start":"2026-04-07T12:56:07.245604Z","end":"2026-04-07T12:56:08.276985Z","steps":["trace[432645070] 'agreement among raft nodes before linearized reading' (duration: 1.031225348s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:08.276988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.272462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-07T12:56:08.276868Z","caller":"traceutil/trace.go:171","msg":"trace[1103274172] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"499.889955ms","start":"2026-04-07T12:56:07.776953Z","end":"2026-04-07T12:56:08.276843Z","steps":["trace[1103274172] 'process raft request' (duration: 499.304851ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:08.27707Z","caller":"traceutil/trace.go:171","msg":"trace[1071523983] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2190; }","duration":"446.380986ms","start":"2026-04-07T12:56:07.830673Z","end":"2026-04-07T12:56:08.277054Z","steps":["trace[1071523983] 'agreement among raft nodes before linearized reading' (duration: 446.243583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:08.277108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.245592Z","time spent":"1.031505034s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-07T12:56:08.277106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.776927Z","time spent":"500.157822ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:56:08.277143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.830655Z","time spent":"446.475758ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-07T12:56:08.276896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.205499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:56:08.277296Z","caller":"traceutil/trace.go:171","msg":"trace[1923119606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2190; }","duration":"963.647609ms","start":"2026-04-07T12:56:07.313638Z","end":"2026-04-07T12:56:08.277286Z","steps":["trace[1923119606] 'agreement among raft nodes before linearized reading' (duration: 963.182628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:08.277327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.313615Z","time spent":"963.704501ms","remote":"127.0.0.1:56988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:56:08.277399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.064337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-07T12:56:08.277502Z","caller":"traceutil/trace.go:171","msg":"trace[1905997660] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2190; }","duration":"304.230181ms","start":"2026-04-07T12:56:07.973248Z","end":"2026-04-07T12:56:08.277478Z","steps":["trace[1905997660] 'agreement among raft nodes before linearized reading' (duration: 303.729008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:08.27758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:07.973216Z","time spent":"304.351314ms","remote":"127.0.0.1:58144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T12:56:09.04225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.979476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:56:09.042533Z","caller":"traceutil/trace.go:171","msg":"trace[397618342] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"760.420041ms","start":"2026-04-07T12:56:08.2821Z","end":"2026-04-07T12:56:09.04252Z","steps":["trace[397618342] 'process raft request' (duration: 209.112138ms)","trace[397618342] 'compare' (duration: 550.659828ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:56:09.042574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:08.282073Z","time spent":"760.482103ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:56:09.042655Z","caller":"traceutil/trace.go:171","msg":"trace[1342342670] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"754.722384ms","start":"2026-04-07T12:56:08.287927Z","end":"2026-04-07T12:56:09.042649Z","steps":["trace[1342342670] 'read index received' (duration: 203.224127ms)","trace[1342342670] 'applied index is now lower than readState.Index' (duration: 551.497687ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:56:09.042784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.857687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-07T12:56:09.042802Z","caller":"traceutil/trace.go:171","msg":"trace[924313018] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2192; }","duration":"754.904779ms","start":"2026-04-07T12:56:08.287893Z","end":"2026-04-07T12:56:09.042798Z","steps":["trace[924313018] 'agreement among raft nodes before linearized reading' (duration: 754.830727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:09.042817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:08.28788Z","time spent":"754.933759ms","remote":"127.0.0.1:58144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-07T12:56:09.20213Z","caller":"traceutil/trace.go:171","msg":"trace[452022195] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"231.141589ms","start":"2026-04-07T12:56:08.970959Z","end":"2026-04-07T12:56:09.2021Z","steps":["trace[452022195] 'process raft request' (duration: 208.806711ms)","trace[452022195] 'compare' (duration: 22.123523ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:56:09.202254Z","caller":"traceutil/trace.go:171","msg":"trace[1836334079] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2277; }","duration":"159.555444ms","start":"2026-04-07T12:56:09.042669Z","end":"2026-04-07T12:56:09.202224Z","steps":["trace[1836334079] 'read index received' (duration: 137.154354ms)","trace[1836334079] 'applied index is now lower than readState.Index' (duration: 22.39965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:56:09.202387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.257244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:56:09.202431Z","caller":"traceutil/trace.go:171","msg":"trace[1172507369] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2194; }","duration":"205.368437ms","start":"2026-04-07T12:56:08.997054Z","end":"2026-04-07T12:56:09.202422Z","steps":["trace[1172507369] 'agreement among raft nodes before linearized reading' (duration: 205.267454ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:09.20254Z","caller":"traceutil/trace.go:171","msg":"trace[498120766] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"231.20544ms","start":"2026-04-07T12:56:08.971319Z","end":"2026-04-07T12:56:09.202525Z","steps":["trace[498120766] 'process raft request' (duration: 230.690008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:09.777981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.962489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:56:09.778102Z","caller":"traceutil/trace.go:171","msg":"trace[156213458] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2279; }","duration":"493.642673ms","start":"2026-04-07T12:56:09.284447Z","end":"2026-04-07T12:56:09.778089Z","steps":["trace[156213458] 'read index received' (duration: 151.411568ms)","trace[156213458] 'applied index is now lower than readState.Index' (duration: 342.229515ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:56:09.778369Z","caller":"traceutil/trace.go:171","msg":"trace[1939458019] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"535.948713ms","start":"2026-04-07T12:56:09.242376Z","end":"2026-04-07T12:56:09.778324Z","steps":["trace[1939458019] 'process raft request' (duration: 193.571983ms)","trace[1939458019] 'compare' (duration: 341.779055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:56:09.778442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.476551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:56:09.778466Z","caller":"traceutil/trace.go:171","msg":"trace[1477782542] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2195; }","duration":"171.539283ms","start":"2026-04-07T12:56:09.60692Z","end":"2026-04-07T12:56:09.778459Z","steps":["trace[1477782542] 'agreement among raft nodes before linearized reading' (duration: 171.479342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:09.778796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.852695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:56:09.779251Z","caller":"traceutil/trace.go:171","msg":"trace[906193115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2195; }","duration":"466.677724ms","start":"2026-04-07T12:56:09.312545Z","end":"2026-04-07T12:56:09.779223Z","steps":["trace[906193115] 'agreement among raft nodes before linearized reading' (duration: 465.835644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:09.778875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:09.242352Z","time spent":"536.109427ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:56:09.779373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:09.312537Z","time spent":"466.817207ms","remote":"127.0.0.1:56988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:56:09.779671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.232901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-07T12:56:09.77971Z","caller":"traceutil/trace.go:171","msg":"trace[370963148] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2195; }","duration":"495.308114ms","start":"2026-04-07T12:56:09.28439Z","end":"2026-04-07T12:56:09.779699Z","steps":["trace[370963148] 'agreement among raft nodes before linearized reading' (duration: 493.806107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:09.779879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:09.284363Z","time spent":"495.428777ms","remote":"127.0.0.1:58144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-07T12:57:17.425555Z","caller":"traceutil/trace.go:171","msg":"trace[453514111] linearizableReadLoop","detail":"{readStateIndex:2701; appliedIndex:2700; }","duration":"113.482139ms","start":"2026-04-07T12:57:17.312054Z","end":"2026-04-07T12:57:17.425536Z","steps":["trace[453514111] 'read index received' (duration: 113.225413ms)","trace[453514111] 'applied index is now lower than readState.Index' (duration: 255.716µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:17.425627Z","caller":"traceutil/trace.go:171","msg":"trace[1299616127] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"113.57641ms","start":"2026-04-07T12:57:17.312036Z","end":"2026-04-07T12:57:17.425612Z","steps":["trace[1299616127] 'process raft request' (duration: 113.308854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:17.425721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.632142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:57:17.425769Z","caller":"traceutil/trace.go:171","msg":"trace[728952156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2600; }","duration":"113.744765ms","start":"2026-04-07T12:57:17.312012Z","end":"2026-04-07T12:57:17.425757Z","steps":["trace[728952156] 'agreement among raft nodes before linearized reading' (duration: 113.610592ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:25.703969Z","caller":"traceutil/trace.go:171","msg":"trace[86314720] linearizableReadLoop","detail":"{readStateIndex:2802; appliedIndex:2801; }","duration":"246.545507ms","start":"2026-04-07T12:57:25.457402Z","end":"2026-04-07T12:57:25.703947Z","steps":["trace[86314720] 'read index received' (duration: 246.417624ms)","trace[86314720] 'applied index is now lower than readState.Index' (duration: 127.063µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:25.703987Z","caller":"traceutil/trace.go:171","msg":"trace[208813711] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"252.275884ms","start":"2026-04-07T12:57:25.451689Z","end":"2026-04-07T12:57:25.703965Z","steps":["trace[208813711] 'process raft request' (duration: 252.126101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:25.704194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.756042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:57:25.70425Z","caller":"traceutil/trace.go:171","msg":"trace[151640330] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2700; }","duration":"246.871035ms","start":"2026-04-07T12:57:25.457369Z","end":"2026-04-07T12:57:25.70424Z","steps":["trace[151640330] 'agreement among raft nodes before linearized reading' (duration: 246.65383ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:25.705115Z","caller":"traceutil/trace.go:171","msg":"trace[935559444] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"189.62884ms","start":"2026-04-07T12:57:25.515464Z","end":"2026-04-07T12:57:25.705092Z","steps":["trace[935559444] 'process raft request' (duration: 189.361053ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:25.705458Z","caller":"traceutil/trace.go:171","msg":"trace[164122066] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"179.602049ms","start":"2026-04-07T12:57:25.52582Z","end":"2026-04-07T12:57:25.705422Z","steps":["trace[164122066] 'process raft request' (duration: 179.133717ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:26.702793Z","caller":"traceutil/trace.go:171","msg":"trace[142513027] linearizableReadLoop","detail":"{readStateIndex:2806; appliedIndex:2805; }","duration":"465.544482ms","start":"2026-04-07T12:57:26.237231Z","end":"2026-04-07T12:57:26.702775Z","steps":["trace[142513027] 'read index received' (duration: 465.369718ms)","trace[142513027] 'applied index is now lower than readState.Index' (duration: 174.194µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:26.702861Z","caller":"traceutil/trace.go:171","msg":"trace[1436157602] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"646.474913ms","start":"2026-04-07T12:57:26.056368Z","end":"2026-04-07T12:57:26.702843Z","steps":["trace[1436157602] 'process raft request' (duration: 646.273848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:26.702917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.648162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:57:26.702944Z","caller":"traceutil/trace.go:171","msg":"trace[2128411661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2703; }","duration":"390.725514ms","start":"2026-04-07T12:57:26.312209Z","end":"2026-04-07T12:57:26.702934Z","steps":["trace[2128411661] 'agreement among raft nodes before linearized reading' (duration: 390.664622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:26.702974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:26.312191Z","time spent":"390.776874ms","remote":"127.0.0.1:56988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T12:57:26.702973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:26.056343Z","time spent":"646.563785ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:57:26.703132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.87962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T12:57:26.7032Z","caller":"traceutil/trace.go:171","msg":"trace[741584617] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2703; }","duration":"465.987072ms","start":"2026-04-07T12:57:26.237189Z","end":"2026-04-07T12:57:26.703176Z","steps":["trace[741584617] 'agreement among raft nodes before linearized reading' (duration: 465.661165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:26.703223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:26.237168Z","time spent":"466.048673ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-04-07T12:57:59.101593Z","caller":"traceutil/trace.go:171","msg":"trace[1348289355] linearizableReadLoop","detail":"{readStateIndex:2989; appliedIndex:2988; }","duration":"244.805863ms","start":"2026-04-07T12:57:58.856757Z","end":"2026-04-07T12:57:59.101563Z","steps":["trace[1348289355] 'read index received' (duration: 244.643ms)","trace[1348289355] 'applied index is now lower than readState.Index' (duration: 157.083µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:59.101682Z","caller":"traceutil/trace.go:171","msg":"trace[953317298] transaction","detail":"{read_only:false; response_revision:2880; number_of_response:1; }","duration":"294.341124ms","start":"2026-04-07T12:57:58.807294Z","end":"2026-04-07T12:57:59.101635Z","steps":["trace[953317298] 'process raft request' (duration: 294.073058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:59.101985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.73825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T12:57:59.102115Z","caller":"traceutil/trace.go:171","msg":"trace[1348210895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2880; }","duration":"241.877914ms","start":"2026-04-07T12:57:58.86018Z","end":"2026-04-07T12:57:59.102058Z","steps":["trace[1348210895] 'agreement among raft nodes before linearized reading' (duration: 241.592687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:59.10212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.213699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:57:59.102195Z","caller":"traceutil/trace.go:171","msg":"trace[1835768753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2880; }","duration":"202.338543ms","start":"2026-04-07T12:57:58.899839Z","end":"2026-04-07T12:57:59.102177Z","steps":["trace[1835768753] 'agreement among raft nodes before linearized reading' (duration: 202.014425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:59.102248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.847985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T12:57:59.102349Z","caller":"traceutil/trace.go:171","msg":"trace[284889898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2880; }","duration":"207.965987ms","start":"2026-04-07T12:57:58.894361Z","end":"2026-04-07T12:57:59.102327Z","steps":["trace[284889898] 'agreement among raft nodes before linearized reading' (duration: 207.622879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:59.102801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.166299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-07T12:57:59.102699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.935232ms","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-04-07T12:57:59.103347Z","caller":"traceutil/trace.go:171","msg":"trace[1560844693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2880; }","duration":"180.851447ms","start":"2026-04-07T12:57:58.9221Z","end":"2026-04-07T12:57:59.102952Z","steps":["trace[1560844693] 'agreement among raft nodes before linearized reading' (duration: 179.703739ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:59.103448Z","caller":"traceutil/trace.go:171","msg":"trace[611345983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2880; }","duration":"246.34211ms","start":"2026-04-07T12:57:58.856716Z","end":"2026-04-07T12:57:59.103058Z","steps":["trace[611345983] 'agreement among raft nodes before linearized reading' (duration: 244.948007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:59.333573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.661998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:57:59.333697Z","caller":"traceutil/trace.go:171","msg":"trace[981316142] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"226.088454ms","start":"2026-04-07T12:57:59.107583Z","end":"2026-04-07T12:57:59.333672Z","steps":["trace[981316142] 'process raft request' (duration: 88.21721ms)","trace[981316142] 'compare' (duration: 137.308749ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:59.334379Z","caller":"traceutil/trace.go:171","msg":"trace[1721133379] transaction","detail":"{read_only:false; response_revision:2883; number_of_response:1; }","duration":"226.130345ms","start":"2026-04-07T12:57:59.108233Z","end":"2026-04-07T12:57:59.334363Z","steps":["trace[1721133379] 'process raft request' (duration: 226.029172ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:59.334432Z","caller":"traceutil/trace.go:171","msg":"trace[692889499] transaction","detail":"{read_only:false; response_revision:2882; number_of_response:1; }","duration":"227.036856ms","start":"2026-04-07T12:57:59.107353Z","end":"2026-04-07T12:57:59.33439Z","steps":["trace[692889499] 'process raft request' (duration: 226.815181ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:59.334831Z","caller":"traceutil/trace.go:171","msg":"trace[2057264448] linearizableReadLoop","detail":"{readStateIndex:2994; appliedIndex:2990; }","duration":"136.92134ms","start":"2026-04-07T12:57:59.197892Z","end":"2026-04-07T12:57:59.334813Z","steps":["trace[2057264448] 'read index received' (duration: 136.225314ms)","trace[2057264448] 'applied index is now lower than readState.Index' (duration: 694.496µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:59.334938Z","caller":"traceutil/trace.go:171","msg":"trace[1101523952] transaction","detail":"{read_only:false; response_revision:2885; number_of_response:1; }","duration":"225.385266ms","start":"2026-04-07T12:57:59.10948Z","end":"2026-04-07T12:57:59.334866Z","steps":["trace[1101523952] 'process raft request' (duration: 225.223123ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:59.334934Z","caller":"traceutil/trace.go:171","msg":"trace[369253499] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"225.792906ms","start":"2026-04-07T12:57:59.109071Z","end":"2026-04-07T12:57:59.334864Z","steps":["trace[369253499] 'process raft request' (duration: 225.251394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:59.335183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.271949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a414781c603066\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-04-07T12:57:59.335224Z","caller":"traceutil/trace.go:171","msg":"trace[1389232251] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a414781c603066; range_end:; response_count:1; response_revision:2885; }","duration":"137.381613ms","start":"2026-04-07T12:57:59.197831Z","end":"2026-04-07T12:57:59.335213Z","steps":["trace[1389232251] 'agreement among raft nodes before linearized reading' (duration: 137.137386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:25.872994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.542429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:25.87312Z","caller":"traceutil/trace.go:171","msg":"trace[1219677921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:3743; }","duration":"163.716932ms","start":"2026-04-07T13:00:25.709385Z","end":"2026-04-07T13:00:25.873102Z","steps":["trace[1219677921] 'count revisions from in-memory index tree' (duration: 163.445028ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:40.757941Z","caller":"traceutil/trace.go:171","msg":"trace[1092566577] transaction","detail":"{read_only:false; response_revision:4557; number_of_response:1; }","duration":"114.704156ms","start":"2026-04-07T13:01:40.643215Z","end":"2026-04-07T13:01:40.757919Z","steps":["trace[1092566577] 'process raft request' (duration: 112.233483ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:40.763567Z","caller":"traceutil/trace.go:171","msg":"trace[1048202113] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"120.000268ms","start":"2026-04-07T13:01:40.643523Z","end":"2026-04-07T13:01:40.763524Z","steps":["trace[1048202113] 'process raft request' (duration: 119.826725ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:40.763601Z","caller":"traceutil/trace.go:171","msg":"trace[1988890689] transaction","detail":"{read_only:false; response_revision:4560; number_of_response:1; }","duration":"109.925445ms","start":"2026-04-07T13:01:40.653659Z","end":"2026-04-07T13:01:40.763585Z","steps":["trace[1988890689] 'process raft request' (duration: 109.861984ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:40.763637Z","caller":"traceutil/trace.go:171","msg":"trace[1693783132] transaction","detail":"{read_only:false; response_revision:4559; number_of_response:1; }","duration":"119.299545ms","start":"2026-04-07T13:01:40.644303Z","end":"2026-04-07T13:01:40.763602Z","steps":["trace[1693783132] 'process raft request' (duration: 119.156313ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:40.763968Z","caller":"traceutil/trace.go:171","msg":"trace[1295096709] linearizableReadLoop","detail":"{readStateIndex:4739; appliedIndex:4735; }","duration":"102.023689ms","start":"2026-04-07T13:01:40.66192Z","end":"2026-04-07T13:01:40.763944Z","steps":["trace[1295096709] 'read index received' (duration: 93.647484ms)","trace[1295096709] 'applied index is now lower than readState.Index' (duration: 8.374835ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:01:40.764193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.260062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.224\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T13:01:40.764251Z","caller":"traceutil/trace.go:171","msg":"trace[646967041] range","detail":"{range_begin:/registry/masterleases/199.19.213.224; range_end:; response_count:1; response_revision:4564; }","duration":"102.359844ms","start":"2026-04-07T13:01:40.661874Z","end":"2026-04-07T13:01:40.764234Z","steps":["trace[646967041] 'agreement among raft nodes before linearized reading' (duration: 102.180831ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:41.944439Z","caller":"traceutil/trace.go:171","msg":"trace[1824805311] linearizableReadLoop","detail":"{readStateIndex:4771; appliedIndex:4770; }","duration":"262.006163ms","start":"2026-04-07T13:01:41.682405Z","end":"2026-04-07T13:01:41.944411Z","steps":["trace[1824805311] 'read index received' (duration: 261.652148ms)","trace[1824805311] 'applied index is now lower than readState.Index' (duration: 352.965µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:01:41.944646Z","caller":"traceutil/trace.go:171","msg":"trace[1713578047] transaction","detail":"{read_only:false; response_revision:4590; number_of_response:1; }","duration":"279.298912ms","start":"2026-04-07T13:01:41.665309Z","end":"2026-04-07T13:01:41.944607Z","steps":["trace[1713578047] 'process raft request' (duration: 278.797863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:41.944682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.265558ms","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-04-07T13:01:41.944869Z","caller":"traceutil/trace.go:171","msg":"trace[318833491] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4590; }","duration":"262.481243ms","start":"2026-04-07T13:01:41.682374Z","end":"2026-04-07T13:01:41.944855Z","steps":["trace[318833491] 'agreement among raft nodes before linearized reading' (duration: 262.266788ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:41.953505Z","caller":"traceutil/trace.go:171","msg":"trace[161518831] transaction","detail":"{read_only:false; response_revision:4591; number_of_response:1; }","duration":"238.819493ms","start":"2026-04-07T13:01:41.714665Z","end":"2026-04-07T13:01:41.953484Z","steps":["trace[161518831] 'process raft request' (duration: 237.438019ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:41.953856Z","caller":"traceutil/trace.go:171","msg":"trace[1053035180] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"149.170795ms","start":"2026-04-07T13:01:41.804676Z","end":"2026-04-07T13:01:41.953847Z","steps":["trace[1053035180] 'process raft request' (duration: 147.788681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:41.954297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.634943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls-b79cr\" ","response":"range_response_count:1 size:2464"} {"level":"info","ts":"2026-04-07T13:01:41.954362Z","caller":"traceutil/trace.go:171","msg":"trace[252804414] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls-b79cr; range_end:; response_count:1; response_revision:4594; }","duration":"200.740176ms","start":"2026-04-07T13:01:41.753611Z","end":"2026-04-07T13:01:41.954351Z","steps":["trace[252804414] 'agreement among raft nodes before linearized reading' (duration: 200.501042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:41.954283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.752408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:01:41.954529Z","caller":"traceutil/trace.go:171","msg":"trace[1933713129] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:4594; }","duration":"248.065313ms","start":"2026-04-07T13:01:41.706453Z","end":"2026-04-07T13:01:41.954519Z","steps":["trace[1933713129] 'agreement among raft nodes before linearized reading' (duration: 247.638775ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:42.785406Z","caller":"traceutil/trace.go:171","msg":"trace[118814442] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4637; }","duration":"113.787738ms","start":"2026-04-07T13:01:42.67159Z","end":"2026-04-07T13:01:42.785378Z","steps":["trace[118814442] 'process raft request' (duration: 108.209722ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:42.78575Z","caller":"traceutil/trace.go:171","msg":"trace[1432991592] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"110.887257ms","start":"2026-04-07T13:01:42.674841Z","end":"2026-04-07T13:01:42.785728Z","steps":["trace[1432991592] 'process raft request' (duration: 110.516091ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:43.169713Z","caller":"traceutil/trace.go:171","msg":"trace[1554445067] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"148.152066ms","start":"2026-04-07T13:01:43.021526Z","end":"2026-04-07T13:01:43.169678Z","steps":["trace[1554445067] 'process raft request' (duration: 142.149573ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:43.170206Z","caller":"traceutil/trace.go:171","msg":"trace[185490361] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"146.455698ms","start":"2026-04-07T13:01:43.023724Z","end":"2026-04-07T13:01:43.170179Z","steps":["trace[185490361] 'process raft request' (duration: 145.865197ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:43.17062Z","caller":"traceutil/trace.go:171","msg":"trace[1581514310] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"145.876647ms","start":"2026-04-07T13:01:43.024736Z","end":"2026-04-07T13:01:43.170613Z","steps":["trace[1581514310] 'process raft request' (duration: 145.260587ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:43.170844Z","caller":"traceutil/trace.go:171","msg":"trace[768217696] linearizableReadLoop","detail":"{readStateIndex:4845; appliedIndex:4842; }","duration":"137.388951ms","start":"2026-04-07T13:01:43.033448Z","end":"2026-04-07T13:01:43.170837Z","steps":["trace[768217696] 'read index received' (duration: 73.364072ms)","trace[768217696] 'applied index is now lower than readState.Index' (duration: 64.023989ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:01:43.171006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.544113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-07T13:01:43.171028Z","caller":"traceutil/trace.go:171","msg":"trace[668316052] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4665; }","duration":"137.603314ms","start":"2026-04-07T13:01:43.033416Z","end":"2026-04-07T13:01:43.17102Z","steps":["trace[668316052] 'agreement among raft nodes before linearized reading' (duration: 137.478702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:43.171214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.20533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:7 size:61021"} {"level":"info","ts":"2026-04-07T13:01:43.171256Z","caller":"traceutil/trace.go:171","msg":"trace[1640269422] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:7; response_revision:4665; }","duration":"128.298684ms","start":"2026-04-07T13:01:43.042945Z","end":"2026-04-07T13:01:43.171243Z","steps":["trace[1640269422] 'agreement among raft nodes before linearized reading' (duration: 128.033358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:43.171467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.168262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:15233"} {"level":"info","ts":"2026-04-07T13:01:43.171496Z","caller":"traceutil/trace.go:171","msg":"trace[1497341705] range","detail":"{range_begin:/registry/statefulsets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4665; }","duration":"124.207243ms","start":"2026-04-07T13:01:43.047278Z","end":"2026-04-07T13:01:43.171486Z","steps":["trace[1497341705] 'agreement among raft nodes before linearized reading' (duration: 124.124391ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:44.233719Z","caller":"traceutil/trace.go:171","msg":"trace[2061501544] linearizableReadLoop","detail":"{readStateIndex:4865; appliedIndex:4864; }","duration":"143.393429ms","start":"2026-04-07T13:01:44.090308Z","end":"2026-04-07T13:01:44.233701Z","steps":["trace[2061501544] 'read index received' (duration: 143.233626ms)","trace[2061501544] 'applied index is now lower than readState.Index' (duration: 158.843µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:01:44.233803Z","caller":"traceutil/trace.go:171","msg":"trace[1599318648] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"143.553772ms","start":"2026-04-07T13:01:44.090208Z","end":"2026-04-07T13:01:44.233762Z","steps":["trace[1599318648] 'process raft request' (duration: 143.381389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:44.23392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.587183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-07T13:01:44.233952Z","caller":"traceutil/trace.go:171","msg":"trace[239230640] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4683; }","duration":"143.639494ms","start":"2026-04-07T13:01:44.090302Z","end":"2026-04-07T13:01:44.233942Z","steps":["trace[239230640] 'agreement among raft nodes before linearized reading' (duration: 143.48775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:44.234119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.215704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T13:01:44.234152Z","caller":"traceutil/trace.go:171","msg":"trace[1768749532] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4683; }","duration":"106.283505ms","start":"2026-04-07T13:01:44.127861Z","end":"2026-04-07T13:01:44.234145Z","steps":["trace[1768749532] 'agreement among raft nodes before linearized reading' (duration: 105.99118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:01.522398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.548579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:02:01.522517Z","caller":"traceutil/trace.go:171","msg":"trace[729744539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4942; }","duration":"209.712422ms","start":"2026-04-07T13:02:01.312782Z","end":"2026-04-07T13:02:01.522494Z","steps":["trace[729744539] 'range keys from in-memory index tree' (duration: 209.467808ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:02.808956Z","caller":"traceutil/trace.go:171","msg":"trace[282035029] linearizableReadLoop","detail":"{readStateIndex:5138; appliedIndex:5137; }","duration":"167.724798ms","start":"2026-04-07T13:02:02.641209Z","end":"2026-04-07T13:02:02.808934Z","steps":["trace[282035029] 'read index received' (duration: 167.562085ms)","trace[282035029] 'applied index is now lower than readState.Index' (duration: 161.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:02:02.809258Z","caller":"traceutil/trace.go:171","msg":"trace[1594925374] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"177.084445ms","start":"2026-04-07T13:02:02.63212Z","end":"2026-04-07T13:02:02.809204Z","steps":["trace[1594925374] 'process raft request' (duration: 176.680228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:02.809948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.077263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T13:02:02.810125Z","caller":"traceutil/trace.go:171","msg":"trace[1530849252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4946; }","duration":"117.192516ms","start":"2026-04-07T13:02:02.692823Z","end":"2026-04-07T13:02:02.810016Z","steps":["trace[1530849252] 'agreement among raft nodes before linearized reading' (duration: 116.297319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:02.809298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.061804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T13:02:02.810423Z","caller":"traceutil/trace.go:171","msg":"trace[1548253857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4946; }","duration":"169.225635ms","start":"2026-04-07T13:02:02.641167Z","end":"2026-04-07T13:02:02.810392Z","steps":["trace[1548253857] 'agreement among raft nodes before linearized reading' (duration: 167.891731ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:08.377669Z","caller":"traceutil/trace.go:171","msg":"trace[194356059] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"332.663928ms","start":"2026-04-07T13:02:08.044974Z","end":"2026-04-07T13:02:08.377638Z","steps":["trace[194356059] 'process raft request' (duration: 332.471094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:08.377851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:08.044949Z","time spent":"332.816471ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:02:08.896421Z","caller":"traceutil/trace.go:171","msg":"trace[675055507] linearizableReadLoop","detail":"{readStateIndex:5190; appliedIndex:5189; }","duration":"424.517303ms","start":"2026-04-07T13:02:08.47187Z","end":"2026-04-07T13:02:08.896388Z","steps":["trace[675055507] 'read index received' (duration: 395.986389ms)","trace[675055507] 'applied index is now lower than readState.Index' (duration: 28.529174ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:02:08.896598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.699746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:02:08.896675Z","caller":"traceutil/trace.go:171","msg":"trace[70996129] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4997; }","duration":"424.825979ms","start":"2026-04-07T13:02:08.471828Z","end":"2026-04-07T13:02:08.896654Z","steps":["trace[70996129] 'agreement among raft nodes before linearized reading' (duration: 424.672446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:08.896728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:08.471809Z","time spent":"424.9069ms","remote":"127.0.0.1:57134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:02:08.896753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.126893ms","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-04-07T13:02:08.896806Z","caller":"traceutil/trace.go:171","msg":"trace[672440174] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4997; }","duration":"421.217074ms","start":"2026-04-07T13:02:08.475574Z","end":"2026-04-07T13:02:08.896791Z","steps":["trace[672440174] 'agreement among raft nodes before linearized reading' (duration: 421.052462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:08.896847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:08.475556Z","time spent":"421.282665ms","remote":"127.0.0.1:57104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-07T13:02:08.897136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.00953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-04-07T13:02:08.897167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.057853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:02:08.897196Z","caller":"traceutil/trace.go:171","msg":"trace[954984388] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4997; }","duration":"132.096721ms","start":"2026-04-07T13:02:08.765078Z","end":"2026-04-07T13:02:08.897175Z","steps":["trace[954984388] 'agreement among raft nodes before linearized reading' (duration: 131.977889ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:08.897213Z","caller":"traceutil/trace.go:171","msg":"trace[1408247098] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4997; }","duration":"369.148146ms","start":"2026-04-07T13:02:08.528053Z","end":"2026-04-07T13:02:08.897201Z","steps":["trace[1408247098] 'agreement among raft nodes before linearized reading' (duration: 369.068913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:08.897251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:08.528029Z","time spent":"369.211537ms","remote":"127.0.0.1:57344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:02:08.89745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.528762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:02:08.897491Z","caller":"traceutil/trace.go:171","msg":"trace[1075931587] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:4997; }","duration":"263.608303ms","start":"2026-04-07T13:02:08.633869Z","end":"2026-04-07T13:02:08.897477Z","steps":["trace[1075931587] 'agreement among raft nodes before linearized reading' (duration: 263.545552ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:08.89649Z","caller":"traceutil/trace.go:171","msg":"trace[412468263] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"445.594433ms","start":"2026-04-07T13:02:08.45086Z","end":"2026-04-07T13:02:08.896455Z","steps":["trace[412468263] 'process raft request' (duration: 416.972307ms)","trace[412468263] 'compare' (duration: 27.886152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:02:08.897965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:08.450836Z","time spent":"447.039429ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:02:09.706633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198695912483827192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:02:10.207225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198695912483827192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:02:10.247287Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.042245283s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T13:02:10.248189Z","caller":"traceutil/trace.go:171","msg":"trace[727473861] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"1.043220171s","start":"2026-04-07T13:02:09.204913Z","end":"2026-04-07T13:02:10.248133Z","steps":["trace[727473861] 'process raft request' (duration: 1.042822124s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:10.248527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:09.204888Z","time spent":"1.043432605s","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:02:10.282405Z","caller":"traceutil/trace.go:171","msg":"trace[1130937730] linearizableReadLoop","detail":"{readStateIndex:5212; appliedIndex:5208; }","duration":"1.076212838s","start":"2026-04-07T13:02:09.206161Z","end":"2026-04-07T13:02:10.282374Z","steps":["trace[1130937730] 'read index received' (duration: 1.04148063s)","trace[1130937730] 'applied index is now lower than readState.Index' (duration: 34.731378ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:02:10.282797Z","caller":"traceutil/trace.go:171","msg":"trace[1249202059] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"1.076832908s","start":"2026-04-07T13:02:09.205953Z","end":"2026-04-07T13:02:10.282786Z","steps":["trace[1249202059] 'process raft request' (duration: 1.069297062s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:10.28291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:09.205935Z","time spent":"1.07688633s","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:02:10.283201Z","caller":"traceutil/trace.go:171","msg":"trace[1593008098] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"1.076818138s","start":"2026-04-07T13:02:09.206355Z","end":"2026-04-07T13:02:10.283173Z","steps":["trace[1593008098] 'process raft request' (duration: 1.075922912s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:10.283254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:09.206348Z","time spent":"1.07687506s","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:02:10.283439Z","caller":"traceutil/trace.go:171","msg":"trace[2143962050] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"1.076472202s","start":"2026-04-07T13:02:09.206956Z","end":"2026-04-07T13:02:10.283428Z","steps":["trace[2143962050] 'process raft request' (duration: 1.075362622s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:10.283489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:09.20695Z","time spent":"1.076510713s","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:02:10.284596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078442417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18a414b3651a02a4\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-04-07T13:02:10.284631Z","caller":"traceutil/trace.go:171","msg":"trace[1696613978] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18a414b3651a02a4; range_end:; response_count:1; response_revision:5019; }","duration":"1.078526199s","start":"2026-04-07T13:02:09.206093Z","end":"2026-04-07T13:02:10.284619Z","steps":["trace[1696613978] 'agreement among raft nodes before linearized reading' (duration: 1.077508611s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:10.284662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:09.206072Z","time spent":"1.07858387s","remote":"127.0.0.1:57048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":774,"request content":"key:\"/registry/events/openstack/rabbitmq-keystone.18a414b3651a02a4\" "} {"level":"warn","ts":"2026-04-07T13:02:10.284907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.622552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:02:10.284934Z","caller":"traceutil/trace.go:171","msg":"trace[952649901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5019; }","duration":"970.650352ms","start":"2026-04-07T13:02:09.314275Z","end":"2026-04-07T13:02:10.284926Z","steps":["trace[952649901] 'agreement among raft nodes before linearized reading' (duration: 970.597231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:10.284954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:09.314244Z","time spent":"970.705094ms","remote":"127.0.0.1:56988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T13:02:10.285227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.789891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-07T13:02:10.285249Z","caller":"traceutil/trace.go:171","msg":"trace[1858607354] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5019; }","duration":"252.813842ms","start":"2026-04-07T13:02:10.032427Z","end":"2026-04-07T13:02:10.285241Z","steps":["trace[1858607354] 'agreement among raft nodes before linearized reading' (duration: 252.742551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:10.285399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.253786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-wqfmr.18a414b5545e1416\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-04-07T13:02:10.285419Z","caller":"traceutil/trace.go:171","msg":"trace[314311825] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-wqfmr.18a414b5545e1416; range_end:; response_count:1; response_revision:5019; }","duration":"707.309538ms","start":"2026-04-07T13:02:09.578101Z","end":"2026-04-07T13:02:10.285411Z","steps":["trace[314311825] 'agreement among raft nodes before linearized reading' (duration: 707.246097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:10.285436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:09.578078Z","time spent":"707.353538ms","remote":"127.0.0.1:57048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":985,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-wqfmr.18a414b5545e1416\" "} {"level":"warn","ts":"2026-04-07T13:02:10.28564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.037207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:02:10.285668Z","caller":"traceutil/trace.go:171","msg":"trace[2118591618] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:5019; }","duration":"921.070327ms","start":"2026-04-07T13:02:09.364592Z","end":"2026-04-07T13:02:10.285662Z","steps":["trace[2118591618] 'agreement among raft nodes before linearized reading' (duration: 920.987286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:10.285694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:09.364582Z","time spent":"921.107348ms","remote":"127.0.0.1:57164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":37,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:02:10.285842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.755423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-07T13:02:10.285871Z","caller":"traceutil/trace.go:171","msg":"trace[1876549014] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5019; }","duration":"924.812264ms","start":"2026-04-07T13:02:09.361052Z","end":"2026-04-07T13:02:10.285864Z","steps":["trace[1876549014] 'agreement among raft nodes before linearized reading' (duration: 924.742382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:10.285892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:09.361033Z","time spent":"924.854994ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-07T13:02:17.137697Z","caller":"traceutil/trace.go:171","msg":"trace[1213038532] linearizableReadLoop","detail":"{readStateIndex:5265; appliedIndex:5261; }","duration":"126.488778ms","start":"2026-04-07T13:02:17.011173Z","end":"2026-04-07T13:02:17.137662Z","steps":["trace[1213038532] 'read index received' (duration: 37.203748ms)","trace[1213038532] 'applied index is now lower than readState.Index' (duration: 89.28375ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:02:17.137987Z","caller":"traceutil/trace.go:171","msg":"trace[910861135] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"136.865509ms","start":"2026-04-07T13:02:17.001076Z","end":"2026-04-07T13:02:17.137942Z","steps":["trace[910861135] 'process raft request' (duration: 136.531333ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:17.138022Z","caller":"traceutil/trace.go:171","msg":"trace[1480169045] transaction","detail":"{read_only:false; response_revision:5068; number_of_response:1; }","duration":"140.058946ms","start":"2026-04-07T13:02:16.997921Z","end":"2026-04-07T13:02:17.13798Z","steps":["trace[1480169045] 'process raft request' (duration: 93.255293ms)","trace[1480169045] 'compare' (duration: 45.907528ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:02:17.138183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.164982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T13:02:17.138239Z","caller":"traceutil/trace.go:171","msg":"trace[1106311451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5070; }","duration":"109.272064ms","start":"2026-04-07T13:02:17.028954Z","end":"2026-04-07T13:02:17.138226Z","steps":["trace[1106311451] 'agreement among raft nodes before linearized reading' (duration: 109.05901ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:17.13799Z","caller":"traceutil/trace.go:171","msg":"trace[1377817008] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"137.587651ms","start":"2026-04-07T13:02:17.000178Z","end":"2026-04-07T13:02:17.137766Z","steps":["trace[1377817008] 'process raft request' (duration: 137.273825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:17.138572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.370765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-07T13:02:17.138681Z","caller":"traceutil/trace.go:171","msg":"trace[576508909] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5070; }","duration":"127.528677ms","start":"2026-04-07T13:02:17.011124Z","end":"2026-04-07T13:02:17.138653Z","steps":["trace[576508909] 'agreement among raft nodes before linearized reading' (duration: 127.329404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:45.654224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.070461ms","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":"info","ts":"2026-04-07T13:02:45.654316Z","caller":"traceutil/trace.go:171","msg":"trace[1717498353] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5398; }","duration":"538.203583ms","start":"2026-04-07T13:02:45.116095Z","end":"2026-04-07T13:02:45.654298Z","steps":["trace[1717498353] 'count revisions from in-memory index tree' (duration: 538.013689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:45.654359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.116082Z","time spent":"538.266614ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":38,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-04-07T13:02:45.654885Z","caller":"traceutil/trace.go:171","msg":"trace[717383786] linearizableReadLoop","detail":"{readStateIndex:5602; appliedIndex:5601; }","duration":"474.306308ms","start":"2026-04-07T13:02:45.180565Z","end":"2026-04-07T13:02:45.654871Z","steps":["trace[717383786] 'read index received' (duration: 445.714609ms)","trace[717383786] 'applied index is now lower than readState.Index' (duration: 28.590149ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:02:45.654995Z","caller":"traceutil/trace.go:171","msg":"trace[928505661] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"481.819871ms","start":"2026-04-07T13:02:45.173155Z","end":"2026-04-07T13:02:45.654975Z","steps":["trace[928505661] 'process raft request' (duration: 453.104479ms)","trace[928505661] 'compare' (duration: 27.342146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:02:45.655083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.173124Z","time spent":"481.902293ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:02:45.655402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.836448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T13:02:45.655456Z","caller":"traceutil/trace.go:171","msg":"trace[580099357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5399; }","duration":"474.91916ms","start":"2026-04-07T13:02:45.180524Z","end":"2026-04-07T13:02:45.655443Z","steps":["trace[580099357] 'agreement among raft nodes before linearized reading' (duration: 474.771738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:45.6555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.180507Z","time spent":"474.980761ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T13:02:46.155048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198695912483828174,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T13:02:46.49313Z","caller":"traceutil/trace.go:171","msg":"trace[1022611726] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"1.232931265s","start":"2026-04-07T13:02:45.260164Z","end":"2026-04-07T13:02:46.493095Z","steps":["trace[1022611726] 'process raft request' (duration: 1.2326982s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:46.493319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.260141Z","time spent":"1.233078787s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:02:46.493383Z","caller":"traceutil/trace.go:171","msg":"trace[147722446] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"1.228948809s","start":"2026-04-07T13:02:45.264411Z","end":"2026-04-07T13:02:46.49336Z","steps":["trace[147722446] 'process raft request' (duration: 1.228626064s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:46.493513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.264396Z","time spent":"1.229042241s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:02:46.493585Z","caller":"traceutil/trace.go:171","msg":"trace[1952498122] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"1.223826673s","start":"2026-04-07T13:02:45.269724Z","end":"2026-04-07T13:02:46.493551Z","steps":["trace[1952498122] 'process raft request' (duration: 1.223560878s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:46.493752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.269711Z","time spent":"1.223951267s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:02:46.501123Z","caller":"traceutil/trace.go:171","msg":"trace[1211288400] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"842.371345ms","start":"2026-04-07T13:02:45.658725Z","end":"2026-04-07T13:02:46.501097Z","steps":["trace[1211288400] 'process raft request' (duration: 842.173731ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:46.501415Z","caller":"traceutil/trace.go:171","msg":"trace[844532757] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"677.777303ms","start":"2026-04-07T13:02:45.823587Z","end":"2026-04-07T13:02:46.501364Z","steps":["trace[844532757] 'process raft request' (duration: 677.464107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:46.501589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.82355Z","time spent":"677.957757ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:02:46.501871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.658703Z","time spent":"842.497388ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:02:46.66794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.674414ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T13:02:46.668063Z","caller":"traceutil/trace.go:171","msg":"trace[1773251904] linearizableReadLoop","detail":"{readStateIndex:5608; appliedIndex:5602; }","duration":"1.013108895s","start":"2026-04-07T13:02:45.654931Z","end":"2026-04-07T13:02:46.66804Z","steps":["trace[1773251904] 'read index received' (duration: 838.074003ms)","trace[1773251904] 'applied index is now lower than readState.Index' (duration: 175.032552ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:02:46.668289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356557689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:02:46.668364Z","caller":"traceutil/trace.go:171","msg":"trace[1352637411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5404; }","duration":"1.356693071s","start":"2026-04-07T13:02:45.311655Z","end":"2026-04-07T13:02:46.668348Z","steps":["trace[1352637411] 'agreement among raft nodes before linearized reading' (duration: 1.356554278s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:46.668407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.31164Z","time spent":"1.356755762s","remote":"127.0.0.1:56988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T13:02:46.668478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337378957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T13:02:46.668588Z","caller":"traceutil/trace.go:171","msg":"trace[1588387781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5404; }","duration":"1.337504959s","start":"2026-04-07T13:02:45.331068Z","end":"2026-04-07T13:02:46.668573Z","steps":["trace[1588387781] 'agreement among raft nodes before linearized reading' (duration: 1.337138842s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:46.668709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.331061Z","time spent":"1.337632042s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T13:02:46.668713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337782495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-07T13:02:46.66891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340183999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T13:02:46.668946Z","caller":"traceutil/trace.go:171","msg":"trace[1618538999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5404; }","duration":"1.338026739s","start":"2026-04-07T13:02:45.330903Z","end":"2026-04-07T13:02:46.66893Z","steps":["trace[1618538999] 'agreement among raft nodes before linearized reading' (duration: 1.337719744s)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:46.668993Z","caller":"traceutil/trace.go:171","msg":"trace[746069933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5404; }","duration":"1.340313582s","start":"2026-04-07T13:02:45.328661Z","end":"2026-04-07T13:02:46.668975Z","steps":["trace[746069933] 'agreement among raft nodes before linearized reading' (duration: 1.340047067s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:46.669021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.330895Z","time spent":"1.338108721s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T13:02:46.669053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.328649Z","time spent":"1.340391673s","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T13:02:46.669038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459194773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-api-5d55977694-2ws6v\" ","response":"range_response_count:1 size:11901"} {"level":"warn","ts":"2026-04-07T13:02:46.668547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372337115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T13:02:46.669146Z","caller":"traceutil/trace.go:171","msg":"trace[498373750] range","detail":"{range_begin:/registry/pods/openstack/keystone-api-5d55977694-2ws6v; range_end:; response_count:1; response_revision:5404; }","duration":"1.459977288s","start":"2026-04-07T13:02:45.20915Z","end":"2026-04-07T13:02:46.669127Z","steps":["trace[498373750] 'agreement among raft nodes before linearized reading' (duration: 1.459628591s)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:46.669188Z","caller":"traceutil/trace.go:171","msg":"trace[234618479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5404; }","duration":"1.373012618s","start":"2026-04-07T13:02:45.296133Z","end":"2026-04-07T13:02:46.669146Z","steps":["trace[234618479] 'agreement among raft nodes before linearized reading' (duration: 1.372181792s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:46.669278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.209112Z","time spent":"1.460119651s","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":11924,"request content":"key:\"/registry/pods/openstack/keystone-api-5d55977694-2ws6v\" "} {"level":"warn","ts":"2026-04-07T13:02:46.669378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.296101Z","time spent":"1.373173682s","remote":"127.0.0.1:57238","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-04-07T13:02:46.669802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.161442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T13:02:46.66986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.42212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:02:46.669932Z","caller":"traceutil/trace.go:171","msg":"trace[137538073] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:5405; }","duration":"650.535392ms","start":"2026-04-07T13:02:46.019381Z","end":"2026-04-07T13:02:46.669916Z","steps":["trace[137538073] 'agreement among raft nodes before linearized reading' (duration: 650.42517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:46.669976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:46.019361Z","time spent":"650.605393ms","remote":"127.0.0.1:57066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-04-07T13:02:46.670085Z","caller":"traceutil/trace.go:171","msg":"trace[1635746206] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5405; }","duration":"783.326975ms","start":"2026-04-07T13:02:45.886576Z","end":"2026-04-07T13:02:46.669903Z","steps":["trace[1635746206] 'agreement among raft nodes before linearized reading' (duration: 783.155582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:46.670204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:45.88655Z","time spent":"783.631501ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-07T13:02:55.747781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2867} {"level":"info","ts":"2026-04-07T13:02:55.82754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2867,"took":"78.483383ms","hash":3598559611,"current-db-size-bytes":52895744,"current-db-size":"53 MB","current-db-size-in-use-bytes":28209152,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-07T13:02:55.827608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3598559611,"revision":2867,"compact-revision":-1} {"level":"info","ts":"2026-04-07T13:07:55.756776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5462} {"level":"info","ts":"2026-04-07T13:07:55.840739Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5462,"took":"82.292658ms","hash":1576550858,"current-db-size-bytes":52895744,"current-db-size":"53 MB","current-db-size-in-use-bytes":18649088,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-07T13:07:55.840792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1576550858,"revision":5462,"compact-revision":2867}