{"level":"warn","ts":"2026-04-28T13:47:23.900966Z","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-28T13:47:23.90106Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.12: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.12:2380","--initial-cluster=instance=https://199.19.213.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.12: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-28T13:47:23.901129Z","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-28T13:47:23.901141Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-04-28T13:47:23.901161Z","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-28T13:47:23.901792Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"]} {"level":"info","ts":"2026-04-28T13:47:23.901869Z","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.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.12: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-28T13:47:23.905843Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.756902ms"} {"level":"info","ts":"2026-04-28T13:47:23.913539Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"790561176553eafe","cluster-id":"e32c72c573d8545f"} {"level":"info","ts":"2026-04-28T13:47:23.913627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T13:47:23.913649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 0"} {"level":"info","ts":"2026-04-28T13:47:23.913662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 790561176553eafe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T13:47:23.913676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 1"} {"level":"info","ts":"2026-04-28T13:47:23.913715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"warn","ts":"2026-04-28T13:47:23.919356Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T13:47:23.921411Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T13:47:23.922794Z","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-28T13:47:23.925587Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"790561176553eafe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T13:47:23.925931Z","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-28T13:47:23.926051Z","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-28T13:47:23.926062Z","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-28T13:47:23.92601Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"790561176553eafe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T13:47:23.926619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"info","ts":"2026-04-28T13:47:23.92898Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","added-peer-id":"790561176553eafe","added-peer-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-04-28T13:47:23.932052Z","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-28T13:47:23.93218Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-04-28T13:47:23.932226Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-04-28T13:47:23.932267Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"790561176553eafe","initial-advertise-peer-urls":["https://199.19.213.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T13:47:23.932292Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T13:47:24.814791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T13:47:24.81486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T13:47:24.814901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgPreVoteResp from 790561176553eafe at term 1"} {"level":"info","ts":"2026-04-28T13:47:24.814921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became candidate at term 2"} {"level":"info","ts":"2026-04-28T13:47:24.814933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgVoteResp from 790561176553eafe at term 2"} {"level":"info","ts":"2026-04-28T13:47:24.815135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became leader at term 2"} {"level":"info","ts":"2026-04-28T13:47:24.815163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 790561176553eafe elected leader 790561176553eafe at term 2"} {"level":"info","ts":"2026-04-28T13:47:24.816348Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"790561176553eafe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.12:2379]}","request-path":"/0/members/790561176553eafe/attributes","cluster-id":"e32c72c573d8545f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T13:47:24.8164Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:47:24.816946Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:47:24.817119Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:47:24.817366Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T13:47:24.817398Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T13:47:24.820209Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T13:47:24.826277Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:47:24.826511Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:47:24.827391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:47:24.827808Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.12:2379"} {"level":"info","ts":"2026-04-28T13:47:41.353696Z","caller":"traceutil/trace.go:171","msg":"trace[705798569] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"127.729628ms","start":"2026-04-28T13:47:41.225948Z","end":"2026-04-28T13:47:41.353678Z","steps":["trace[705798569] 'process raft request' (duration: 114.91407ms)","trace[705798569] 'compare' (duration: 12.734223ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:47:49.544837Z","caller":"traceutil/trace.go:171","msg":"trace[1287263868] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"115.779081ms","start":"2026-04-28T13:47:49.429029Z","end":"2026-04-28T13:47:49.544808Z","steps":["trace[1287263868] 'process raft request' (duration: 115.637975ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:52.468738Z","caller":"traceutil/trace.go:171","msg":"trace[828316146] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"114.412715ms","start":"2026-04-28T13:47:52.354299Z","end":"2026-04-28T13:47:52.468712Z","steps":["trace[828316146] 'process raft request' (duration: 114.239764ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:52.705179Z","caller":"traceutil/trace.go:171","msg":"trace[670464209] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"171.355007ms","start":"2026-04-28T13:47:52.533795Z","end":"2026-04-28T13:47:52.70515Z","steps":["trace[670464209] 'process raft request' (duration: 168.485218ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.078444Z","caller":"traceutil/trace.go:171","msg":"trace[786367375] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"108.933573ms","start":"2026-04-28T13:47:52.969483Z","end":"2026-04-28T13:47:53.078417Z","steps":["trace[786367375] 'process raft request' (duration: 108.887235ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.078562Z","caller":"traceutil/trace.go:171","msg":"trace[215034494] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"109.212122ms","start":"2026-04-28T13:47:52.969321Z","end":"2026-04-28T13:47:53.078533Z","steps":["trace[215034494] 'process raft request' (duration: 51.881641ms)","trace[215034494] 'compare' (duration: 56.931321ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:47:53.078625Z","caller":"traceutil/trace.go:171","msg":"trace[2054045792] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"109.189338ms","start":"2026-04-28T13:47:52.969427Z","end":"2026-04-28T13:47:53.078616Z","steps":["trace[2054045792] 'process raft request' (duration: 108.850928ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.082251Z","caller":"traceutil/trace.go:171","msg":"trace[1093044735] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"103.001176ms","start":"2026-04-28T13:47:52.979226Z","end":"2026-04-28T13:47:53.082227Z","steps":["trace[1093044735] 'process raft request' (duration: 100.801078ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.084022Z","caller":"traceutil/trace.go:171","msg":"trace[327066108] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"101.91203ms","start":"2026-04-28T13:47:52.982047Z","end":"2026-04-28T13:47:53.083959Z","steps":["trace[327066108] 'process raft request' (duration: 98.05557ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.084844Z","caller":"traceutil/trace.go:171","msg":"trace[77948549] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"105.088993ms","start":"2026-04-28T13:47:52.979605Z","end":"2026-04-28T13:47:53.084694Z","steps":["trace[77948549] 'process raft request' (duration: 100.470079ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.084958Z","caller":"traceutil/trace.go:171","msg":"trace[1492953713] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"109.330755ms","start":"2026-04-28T13:47:52.975607Z","end":"2026-04-28T13:47:53.084938Z","steps":["trace[1492953713] 'process raft request' (duration: 104.344507ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.085048Z","caller":"traceutil/trace.go:171","msg":"trace[846363865] transaction","detail":"{read_only:false; number_of_response:0; response_revision:477; }","duration":"106.405566ms","start":"2026-04-28T13:47:52.978628Z","end":"2026-04-28T13:47:53.085033Z","steps":["trace[846363865] 'process raft request' (duration: 101.38203ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.088755Z","caller":"traceutil/trace.go:171","msg":"trace[529045278] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"102.165661ms","start":"2026-04-28T13:47:52.985451Z","end":"2026-04-28T13:47:53.087617Z","steps":["trace[529045278] 'process raft request' (duration: 100.876784ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.088928Z","caller":"traceutil/trace.go:171","msg":"trace[1136783754] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"105.016165ms","start":"2026-04-28T13:47:52.983881Z","end":"2026-04-28T13:47:53.088898Z","steps":["trace[1136783754] 'process raft request' (duration: 100.246494ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.089069Z","caller":"traceutil/trace.go:171","msg":"trace[1735304780] transaction","detail":"{read_only:false; number_of_response:1; response_revision:480; }","duration":"106.698154ms","start":"2026-04-28T13:47:52.982224Z","end":"2026-04-28T13:47:53.088923Z","steps":["trace[1735304780] 'process raft request' (duration: 97.900314ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.08998Z","caller":"traceutil/trace.go:171","msg":"trace[605864615] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"105.31419ms","start":"2026-04-28T13:47:52.98232Z","end":"2026-04-28T13:47:53.087634Z","steps":["trace[605864615] 'process raft request' (duration: 97.845104ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:53.82259Z","caller":"traceutil/trace.go:171","msg":"trace[2074821223] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"479.959836ms","start":"2026-04-28T13:47:53.342599Z","end":"2026-04-28T13:47:53.822558Z","steps":["trace[2074821223] 'read index received' (duration: 475.841051ms)","trace[2074821223] 'applied index is now lower than readState.Index' (duration: 4.117415ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:47:53.822757Z","caller":"traceutil/trace.go:171","msg":"trace[1929108811] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"480.237385ms","start":"2026-04-28T13:47:53.342481Z","end":"2026-04-28T13:47:53.822719Z","steps":["trace[1929108811] 'process raft request' (duration: 476.025113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:53.822879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.252218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T13:47:53.822934Z","caller":"traceutil/trace.go:171","msg":"trace[405942590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"480.361436ms","start":"2026-04-28T13:47:53.342564Z","end":"2026-04-28T13:47:53.822926Z","steps":["trace[405942590] 'agreement among raft nodes before linearized reading' (duration: 480.166353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:53.822972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:47:53.342546Z","time spent":"480.418987ms","remote":"127.0.0.1:43314","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-28T13:47:53.823252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:47:53.342462Z","time spent":"480.389112ms","remote":"127.0.0.1:43078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:47:53.831457Z","caller":"traceutil/trace.go:171","msg":"trace[1026187091] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"445.239979ms","start":"2026-04-28T13:47:53.386206Z","end":"2026-04-28T13:47:53.831446Z","steps":["trace[1026187091] 'process raft request' (duration: 445.206843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:53.831543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:47:53.386187Z","time spent":"445.310831ms","remote":"127.0.0.1:43078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:47:53.831598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.04593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:47:53.831688Z","caller":"traceutil/trace.go:171","msg":"trace[1528319149] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"472.733093ms","start":"2026-04-28T13:47:53.358944Z","end":"2026-04-28T13:47:53.831677Z","steps":["trace[1528319149] 'process raft request' (duration: 472.357226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:53.831734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:47:53.358925Z","time spent":"472.784061ms","remote":"127.0.0.1:43078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:47:53.831683Z","caller":"traceutil/trace.go:171","msg":"trace[340245496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"265.15909ms","start":"2026-04-28T13:47:53.566497Z","end":"2026-04-28T13:47:53.831656Z","steps":["trace[340245496] 'agreement among raft nodes before linearized reading' (duration: 264.984129ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:54.224874Z","caller":"traceutil/trace.go:171","msg":"trace[485590519] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"133.614288ms","start":"2026-04-28T13:47:54.091207Z","end":"2026-04-28T13:47:54.224821Z","steps":["trace[485590519] 'process raft request' (duration: 90.706978ms)","trace[485590519] 'compare' (duration: 42.658536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:47:54.615064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.17587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:47:54.615273Z","caller":"traceutil/trace.go:171","msg":"trace[150465637] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"354.231393ms","start":"2026-04-28T13:47:54.261006Z","end":"2026-04-28T13:47:54.615237Z","steps":["trace[150465637] 'process raft request' (duration: 202.633748ms)","trace[150465637] 'compare' (duration: 150.93958ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:47:54.617755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:47:54.260972Z","time spent":"356.65974ms","remote":"127.0.0.1:43078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:47:54.623798Z","caller":"traceutil/trace.go:171","msg":"trace[1122511179] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:550; }","duration":"151.68449ms","start":"2026-04-28T13:47:54.472078Z","end":"2026-04-28T13:47:54.623763Z","steps":["trace[1122511179] 'read index received' (duration: 142.696627ms)","trace[1122511179] 'applied index is now lower than readState.Index' (duration: 8.986883ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:47:54.623947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.85051ms","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-28T13:47:54.623982Z","caller":"traceutil/trace.go:171","msg":"trace[649839978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"151.935223ms","start":"2026-04-28T13:47:54.472033Z","end":"2026-04-28T13:47:54.623969Z","steps":["trace[649839978] 'agreement among raft nodes before linearized reading' (duration: 151.817823ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:47:54.624493Z","caller":"traceutil/trace.go:171","msg":"trace[1364798212] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"309.859987ms","start":"2026-04-28T13:47:54.314618Z","end":"2026-04-28T13:47:54.624478Z","steps":["trace[1364798212] 'process raft request' (duration: 303.328183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:54.624561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:47:54.31458Z","time spent":"309.938212ms","remote":"127.0.0.1:43078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:47:54.912508Z","caller":"traceutil/trace.go:171","msg":"trace[757472365] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"112.637956ms","start":"2026-04-28T13:47:54.799836Z","end":"2026-04-28T13:47:54.912474Z","steps":["trace[757472365] 'process raft request' (duration: 95.869541ms)","trace[757472365] 'compare' (duration: 16.157048ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:47:57.008331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:47:56.085351Z","time spent":"922.974121ms","remote":"127.0.0.1:37112","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T13:47:57.008558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.232657ms","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-28T13:47:57.008631Z","caller":"traceutil/trace.go:171","msg":"trace[2059173357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"372.376061ms","start":"2026-04-28T13:47:56.636236Z","end":"2026-04-28T13:47:57.008613Z","steps":["trace[2059173357] 'range keys from in-memory index tree' (duration: 372.033542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:57.00867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:47:56.636215Z","time spent":"372.447174ms","remote":"127.0.0.1:43314","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-28T13:47:57.008922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.51888ms","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-28T13:47:57.008953Z","caller":"traceutil/trace.go:171","msg":"trace[338939558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"275.582412ms","start":"2026-04-28T13:47:56.733361Z","end":"2026-04-28T13:47:57.008944Z","steps":["trace[338939558] 'range keys from in-memory index tree' (duration: 275.416873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:47:57.009061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.883925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:47:57.009112Z","caller":"traceutil/trace.go:171","msg":"trace[842851183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"135.015217ms","start":"2026-04-28T13:47:56.87408Z","end":"2026-04-28T13:47:57.009095Z","steps":["trace[842851183] 'range keys from in-memory index tree' (duration: 134.803571ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:01.25355Z","caller":"traceutil/trace.go:171","msg":"trace[479334162] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"202.273538ms","start":"2026-04-28T13:48:01.051249Z","end":"2026-04-28T13:48:01.253522Z","steps":["trace[479334162] 'read index received' (duration: 201.587454ms)","trace[479334162] 'applied index is now lower than readState.Index' (duration: 684.874µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:48:01.25372Z","caller":"traceutil/trace.go:171","msg":"trace[1681158999] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"344.667904ms","start":"2026-04-28T13:48:00.909043Z","end":"2026-04-28T13:48:01.253711Z","steps":["trace[1681158999] 'process raft request' (duration: 344.031607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:01.253826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:48:00.909012Z","time spent":"344.740215ms","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:48:01.253917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.301582ms","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-28T13:48:01.253991Z","caller":"traceutil/trace.go:171","msg":"trace[1741593520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"198.417301ms","start":"2026-04-28T13:48:01.055549Z","end":"2026-04-28T13:48:01.253967Z","steps":["trace[1741593520] 'agreement among raft nodes before linearized reading' (duration: 198.223059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:01.254037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.75686ms","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-28T13:48:01.254155Z","caller":"traceutil/trace.go:171","msg":"trace[19103629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"202.926257ms","start":"2026-04-28T13:48:01.051208Z","end":"2026-04-28T13:48:01.254134Z","steps":["trace[19103629] 'agreement among raft nodes before linearized reading' (duration: 202.612095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:02.54888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27448372s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:48:02.54925Z","caller":"traceutil/trace.go:171","msg":"trace[715444093] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"1.261051224s","start":"2026-04-28T13:48:01.288179Z","end":"2026-04-28T13:48:02.54923Z","steps":["trace[715444093] 'read index received' (duration: 80.422842ms)","trace[715444093] 'applied index is now lower than readState.Index' (duration: 1.180627321s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:48:02.549613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261428076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b4s9s\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-28T13:48:02.549634Z","caller":"traceutil/trace.go:171","msg":"trace[1029829219] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.290618146s","start":"2026-04-28T13:48:01.258983Z","end":"2026-04-28T13:48:02.549601Z","steps":["trace[1029829219] 'process raft request' (duration: 15.19464ms)","trace[1029829219] 'compare' (duration: 1.274231497s)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:48:02.549692Z","caller":"traceutil/trace.go:171","msg":"trace[602022195] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.289459222s","start":"2026-04-28T13:48:01.259927Z","end":"2026-04-28T13:48:02.549387Z","steps":["trace[602022195] 'process raft request' (duration: 1.289159183s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:02.549734Z","caller":"traceutil/trace.go:171","msg":"trace[1857168657] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b4s9s; range_end:; response_count:1; response_revision:580; }","duration":"1.261557067s","start":"2026-04-28T13:48:01.288141Z","end":"2026-04-28T13:48:02.549698Z","steps":["trace[1857168657] 'agreement among raft nodes before linearized reading' (duration: 1.261425946s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:02.549774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:48:01.288121Z","time spent":"1.261642953s","remote":"127.0.0.1:43230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-b4s9s\" "} {"level":"warn","ts":"2026-04-28T13:48:02.549843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:48:01.25896Z","time spent":"1.290783914s","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:48:02.549843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:48:01.259914Z","time spent":"1.289846777s","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:48:02.671219Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.302628973s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T13:48:02.674517Z","caller":"traceutil/trace.go:171","msg":"trace[1838163309] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"125.170352ms","start":"2026-04-28T13:48:02.549324Z","end":"2026-04-28T13:48:02.674494Z","steps":["trace[1838163309] 'read index received' (duration: 122.240823ms)","trace[1838163309] 'applied index is now lower than readState.Index' (duration: 2.928559ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:48:02.674599Z","caller":"traceutil/trace.go:171","msg":"trace[149454706] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.361259004s","start":"2026-04-28T13:48:01.313292Z","end":"2026-04-28T13:48:02.674551Z","steps":["trace[149454706] 'process raft request' (duration: 1.358273226s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:02.67463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.713805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T13:48:02.674668Z","caller":"traceutil/trace.go:171","msg":"trace[1314746701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"802.789678ms","start":"2026-04-28T13:48:01.871868Z","end":"2026-04-28T13:48:02.674657Z","steps":["trace[1314746701] 'agreement among raft nodes before linearized reading' (duration: 802.691421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:02.674685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.250371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-28T13:48:02.674699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:48:01.871813Z","time spent":"802.877202ms","remote":"127.0.0.1:43314","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":"info","ts":"2026-04-28T13:48:02.674725Z","caller":"traceutil/trace.go:171","msg":"trace[2118327591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"734.328034ms","start":"2026-04-28T13:48:01.940383Z","end":"2026-04-28T13:48:02.674711Z","steps":["trace[2118327591] 'agreement among raft nodes before linearized reading' (duration: 734.166467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:02.674734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:48:01.313249Z","time spent":"1.361428493s","remote":"127.0.0.1:43272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:48:02.67475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:48:01.940361Z","time spent":"734.381613ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T13:48:02.684412Z","caller":"traceutil/trace.go:171","msg":"trace[706693409] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"119.324496ms","start":"2026-04-28T13:48:02.565054Z","end":"2026-04-28T13:48:02.684379Z","steps":["trace[706693409] 'process raft request' (duration: 119.129823ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:04.479508Z","caller":"traceutil/trace.go:171","msg":"trace[1820396145] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"136.411566ms","start":"2026-04-28T13:48:04.343065Z","end":"2026-04-28T13:48:04.479476Z","steps":["trace[1820396145] 'process raft request' (duration: 135.826269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:06.857587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.070939ms","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-28T13:48:06.857655Z","caller":"traceutil/trace.go:171","msg":"trace[325342753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"291.194179ms","start":"2026-04-28T13:48:06.566444Z","end":"2026-04-28T13:48:06.857639Z","steps":["trace[325342753] 'range keys from in-memory index tree' (duration: 290.917874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:06.857576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.638773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-28T13:48:06.857767Z","caller":"traceutil/trace.go:171","msg":"trace[773395581] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:643; }","duration":"316.86191ms","start":"2026-04-28T13:48:06.540889Z","end":"2026-04-28T13:48:06.857751Z","steps":["trace[773395581] 'range keys from in-memory index tree' (duration: 316.521185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:06.857807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:48:06.540872Z","time spent":"316.92206ms","remote":"127.0.0.1:43078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-04-28T13:48:06.85781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.705124ms","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-28T13:48:06.857833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.024599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:48:06.857897Z","caller":"traceutil/trace.go:171","msg":"trace[2136745129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"294.827554ms","start":"2026-04-28T13:48:06.563051Z","end":"2026-04-28T13:48:06.857878Z","steps":["trace[2136745129] 'range keys from in-memory index tree' (duration: 294.531115ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:06.857909Z","caller":"traceutil/trace.go:171","msg":"trace[649168697] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"117.470535ms","start":"2026-04-28T13:48:06.740425Z","end":"2026-04-28T13:48:06.857895Z","steps":["trace[649168697] 'read index received' (duration: 12.328237ms)","trace[649168697] 'applied index is now lower than readState.Index' (duration: 105.141478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:48:06.858054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.633843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-28T13:48:06.858026Z","caller":"traceutil/trace.go:171","msg":"trace[1071187089] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"124.625007ms","start":"2026-04-28T13:48:06.733309Z","end":"2026-04-28T13:48:06.857934Z","steps":["trace[1071187089] 'process raft request' (duration: 19.446403ms)","trace[1071187089] 'compare' (duration: 104.925832ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:48:06.85815Z","caller":"traceutil/trace.go:171","msg":"trace[771566543] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:644; }","duration":"117.696834ms","start":"2026-04-28T13:48:06.740388Z","end":"2026-04-28T13:48:06.858084Z","steps":["trace[771566543] 'agreement among raft nodes before linearized reading' (duration: 117.575894ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:07.110718Z","caller":"traceutil/trace.go:171","msg":"trace[1634317581] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:668; }","duration":"234.109635ms","start":"2026-04-28T13:48:06.876585Z","end":"2026-04-28T13:48:07.110694Z","steps":["trace[1634317581] 'read index received' (duration: 79.147024ms)","trace[1634317581] 'applied index is now lower than readState.Index' (duration: 154.961571ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:48:07.110822Z","caller":"traceutil/trace.go:171","msg":"trace[1256600144] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"248.382052ms","start":"2026-04-28T13:48:06.862399Z","end":"2026-04-28T13:48:07.110781Z","steps":["trace[1256600144] 'process raft request' (duration: 248.050058ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:07.110878Z","caller":"traceutil/trace.go:171","msg":"trace[366264679] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"247.919947ms","start":"2026-04-28T13:48:06.862933Z","end":"2026-04-28T13:48:07.110853Z","steps":["trace[366264679] 'process raft request' (duration: 247.727175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:07.11089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.290375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:48:07.111033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.49033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-28T13:48:07.111036Z","caller":"traceutil/trace.go:171","msg":"trace[1268223469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"234.458082ms","start":"2026-04-28T13:48:06.876547Z","end":"2026-04-28T13:48:07.111005Z","steps":["trace[1268223469] 'agreement among raft nodes before linearized reading' (duration: 234.274163ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:07.111069Z","caller":"traceutil/trace.go:171","msg":"trace[753320774] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:648; }","duration":"169.542837ms","start":"2026-04-28T13:48:06.941512Z","end":"2026-04-28T13:48:07.111055Z","steps":["trace[753320774] 'agreement among raft nodes before linearized reading' (duration: 169.412036ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:07.11121Z","caller":"traceutil/trace.go:171","msg":"trace[548092473] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"248.460705ms","start":"2026-04-28T13:48:06.862726Z","end":"2026-04-28T13:48:07.111186Z","steps":["trace[548092473] 'process raft request' (duration: 247.885731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:18.707509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.429765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-28T13:48:18.707601Z","caller":"traceutil/trace.go:171","msg":"trace[1851143087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"486.565575ms","start":"2026-04-28T13:48:18.221013Z","end":"2026-04-28T13:48:18.707579Z","steps":["trace[1851143087] 'range keys from in-memory index tree' (duration: 486.174396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:18.707654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:48:18.220994Z","time spent":"486.650159ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T13:48:18.708159Z","caller":"traceutil/trace.go:171","msg":"trace[1319822330] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"289.784308ms","start":"2026-04-28T13:48:18.418336Z","end":"2026-04-28T13:48:18.70812Z","steps":["trace[1319822330] 'process raft request' (duration: 260.223989ms)","trace[1319822330] 'compare' (duration: 28.815193ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:48:18.708307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.696242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:48:18.70838Z","caller":"traceutil/trace.go:171","msg":"trace[1006634849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"175.823511ms","start":"2026-04-28T13:48:18.532539Z","end":"2026-04-28T13:48:18.708362Z","steps":["trace[1006634849] 'agreement among raft nodes before linearized reading' (duration: 175.695671ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:18.709008Z","caller":"traceutil/trace.go:171","msg":"trace[1141668923] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"175.472817ms","start":"2026-04-28T13:48:18.53259Z","end":"2026-04-28T13:48:18.708062Z","steps":["trace[1141668923] 'read index received' (duration: 145.910878ms)","trace[1141668923] 'applied index is now lower than readState.Index' (duration: 29.561019ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:48:52.255089Z","caller":"traceutil/trace.go:171","msg":"trace[1139793911] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"167.041445ms","start":"2026-04-28T13:48:52.088019Z","end":"2026-04-28T13:48:52.255061Z","steps":["trace[1139793911] 'read index received' (duration: 166.893154ms)","trace[1139793911] 'applied index is now lower than readState.Index' (duration: 147.001µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:48:52.255374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.333717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:48:52.255425Z","caller":"traceutil/trace.go:171","msg":"trace[32724676] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1158; }","duration":"167.448042ms","start":"2026-04-28T13:48:52.087962Z","end":"2026-04-28T13:48:52.25541Z","steps":["trace[32724676] 'agreement among raft nodes before linearized reading' (duration: 167.325635ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:48:52.255364Z","caller":"traceutil/trace.go:171","msg":"trace[1602346677] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"239.57447ms","start":"2026-04-28T13:48:52.015716Z","end":"2026-04-28T13:48:52.25529Z","steps":["trace[1602346677] 'process raft request' (duration: 239.150739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:48:52.255472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.395971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:48:52.255639Z","caller":"traceutil/trace.go:171","msg":"trace[2009539213] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1158; }","duration":"127.611134ms","start":"2026-04-28T13:48:52.127986Z","end":"2026-04-28T13:48:52.255597Z","steps":["trace[2009539213] 'agreement among raft nodes before linearized reading' (duration: 127.31105ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:49:16.007549Z","caller":"traceutil/trace.go:171","msg":"trace[2076647969] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"132.07696ms","start":"2026-04-28T13:49:15.875452Z","end":"2026-04-28T13:49:16.007529Z","steps":["trace[2076647969] 'read index received' (duration: 131.927849ms)","trace[2076647969] 'applied index is now lower than readState.Index' (duration: 146.591µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:49:16.007665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.214019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:49:16.007693Z","caller":"traceutil/trace.go:171","msg":"trace[951487155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1424; }","duration":"132.311615ms","start":"2026-04-28T13:49:15.875371Z","end":"2026-04-28T13:49:16.007683Z","steps":["trace[951487155] 'agreement among raft nodes before linearized reading' (duration: 132.253236ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:49:16.007708Z","caller":"traceutil/trace.go:171","msg":"trace[1557183513] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"292.035867ms","start":"2026-04-28T13:49:15.715636Z","end":"2026-04-28T13:49:16.007672Z","steps":["trace[1557183513] 'process raft request' (duration: 291.76867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:49:49.437152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.874947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:49:49.437333Z","caller":"traceutil/trace.go:171","msg":"trace[836732484] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1939; }","duration":"134.555649ms","start":"2026-04-28T13:49:49.302765Z","end":"2026-04-28T13:49:49.437321Z","steps":["trace[836732484] 'read index received' (duration: 122.126778ms)","trace[836732484] 'applied index is now lower than readState.Index' (duration: 12.428281ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:49:49.437476Z","caller":"traceutil/trace.go:171","msg":"trace[1890470267] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"406.102958ms","start":"2026-04-28T13:49:49.031365Z","end":"2026-04-28T13:49:49.437468Z","steps":["trace[1890470267] 'process raft request' (duration: 228.843667ms)","trace[1890470267] 'compare' (duration: 176.805097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:49:49.437524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:49:49.031337Z","time spent":"406.158345ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:49:49.437689Z","caller":"traceutil/trace.go:171","msg":"trace[1435801999] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"282.717413ms","start":"2026-04-28T13:49:49.154961Z","end":"2026-04-28T13:49:49.437679Z","steps":["trace[1435801999] 'process raft request' (duration: 282.279681ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:49:49.437805Z","caller":"traceutil/trace.go:171","msg":"trace[671030549] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"235.232612ms","start":"2026-04-28T13:49:49.202547Z","end":"2026-04-28T13:49:49.43778Z","steps":["trace[671030549] 'process raft request' (duration: 234.752245ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:49:49.43793Z","caller":"traceutil/trace.go:171","msg":"trace[864575814] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"246.005011ms","start":"2026-04-28T13:49:49.191911Z","end":"2026-04-28T13:49:49.437916Z","steps":["trace[864575814] 'process raft request' (duration: 245.362241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:49:49.438046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.27408ms","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-28T13:49:49.438258Z","caller":"traceutil/trace.go:171","msg":"trace[942762434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1872; }","duration":"135.475978ms","start":"2026-04-28T13:49:49.302749Z","end":"2026-04-28T13:49:49.438225Z","steps":["trace[942762434] 'agreement among raft nodes before linearized reading' (duration: 135.098715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:49:49.681678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.778429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T13:49:49.681762Z","caller":"traceutil/trace.go:171","msg":"trace[1049404633] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1873; }","duration":"119.920429ms","start":"2026-04-28T13:49:49.561823Z","end":"2026-04-28T13:49:49.681743Z","steps":["trace[1049404633] 'range keys from in-memory index tree' (duration: 119.64246ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:49:49.807739Z","caller":"traceutil/trace.go:171","msg":"trace[400032838] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"120.355759ms","start":"2026-04-28T13:49:49.687358Z","end":"2026-04-28T13:49:49.807713Z","steps":["trace[400032838] 'process raft request' (duration: 120.152561ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:49:49.816638Z","caller":"traceutil/trace.go:171","msg":"trace[1696068336] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"128.938602ms","start":"2026-04-28T13:49:49.687681Z","end":"2026-04-28T13:49:49.81662Z","steps":["trace[1696068336] 'process raft request' (duration: 128.813075ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:49:49.817054Z","caller":"traceutil/trace.go:171","msg":"trace[35443078] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"129.289401ms","start":"2026-04-28T13:49:49.687754Z","end":"2026-04-28T13:49:49.817043Z","steps":["trace[35443078] 'process raft request' (duration: 128.833067ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:49:49.817029Z","caller":"traceutil/trace.go:171","msg":"trace[1796023758] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1944; }","duration":"102.669641ms","start":"2026-04-28T13:49:49.714328Z","end":"2026-04-28T13:49:49.816998Z","steps":["trace[1796023758] 'read index received' (duration: 93.244022ms)","trace[1796023758] 'applied index is now lower than readState.Index' (duration: 9.424309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:49:49.817285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.954422ms","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-28T13:49:49.817328Z","caller":"traceutil/trace.go:171","msg":"trace[800353598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1877; }","duration":"103.042973ms","start":"2026-04-28T13:49:49.714275Z","end":"2026-04-28T13:49:49.817318Z","steps":["trace[800353598] 'agreement among raft nodes before linearized reading' (duration: 102.914956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:49:49.817593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.110514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:49:49.817628Z","caller":"traceutil/trace.go:171","msg":"trace[1796229223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1877; }","duration":"102.146689ms","start":"2026-04-28T13:49:49.715472Z","end":"2026-04-28T13:49:49.817619Z","steps":["trace[1796229223] 'agreement among raft nodes before linearized reading' (duration: 102.054676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:49:50.27822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.228625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:49:50.27832Z","caller":"traceutil/trace.go:171","msg":"trace[1079362773] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"442.082579ms","start":"2026-04-28T13:49:49.836221Z","end":"2026-04-28T13:49:50.278304Z","steps":["trace[1079362773] 'read index received' (duration: 50.592346ms)","trace[1079362773] 'applied index is now lower than readState.Index' (duration: 391.488773ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:49:50.278525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.297749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ea5cb290-ea67-4907-9a81-a62b699b3652\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-28T13:49:50.278583Z","caller":"traceutil/trace.go:171","msg":"trace[942296335] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ea5cb290-ea67-4907-9a81-a62b699b3652; range_end:; response_count:1; response_revision:1879; }","duration":"442.377192ms","start":"2026-04-28T13:49:49.836195Z","end":"2026-04-28T13:49:50.278572Z","steps":["trace[942296335] 'agreement among raft nodes before linearized reading' (duration: 442.201437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:49:50.278622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:49:49.836182Z","time spent":"442.431969ms","remote":"127.0.0.1:43230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ea5cb290-ea67-4907-9a81-a62b699b3652\" "} {"level":"warn","ts":"2026-04-28T13:49:50.278536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.572625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:49:50.278418Z","caller":"traceutil/trace.go:171","msg":"trace[2043204038] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"454.992088ms","start":"2026-04-28T13:49:49.823387Z","end":"2026-04-28T13:49:50.278379Z","steps":["trace[2043204038] 'process raft request' (duration: 63.473311ms)","trace[2043204038] 'compare' (duration: 391.066304ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:49:50.278966Z","caller":"traceutil/trace.go:171","msg":"trace[744161441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1879; }","duration":"404.046741ms","start":"2026-04-28T13:49:49.874902Z","end":"2026-04-28T13:49:50.278949Z","steps":["trace[744161441] 'agreement among raft nodes before linearized reading' (duration: 403.578426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:49:50.279297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:49:49.874884Z","time spent":"404.396029ms","remote":"127.0.0.1:39966","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-28T13:49:50.279393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:49:49.823368Z","time spent":"455.629288ms","remote":"127.0.0.1:43314","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-28T13:50:03.671798Z","caller":"traceutil/trace.go:171","msg":"trace[1357212843] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"181.524127ms","start":"2026-04-28T13:50:03.490257Z","end":"2026-04-28T13:50:03.671781Z","steps":["trace[1357212843] 'process raft request' (duration: 181.39759ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:03.675609Z","caller":"traceutil/trace.go:171","msg":"trace[478052198] linearizableReadLoop","detail":"{readStateIndex:2072; appliedIndex:2070; }","duration":"166.402235ms","start":"2026-04-28T13:50:03.509178Z","end":"2026-04-28T13:50:03.67558Z","steps":["trace[478052198] 'read index received' (duration: 162.535144ms)","trace[478052198] 'applied index is now lower than readState.Index' (duration: 3.866181ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:03.67571Z","caller":"traceutil/trace.go:171","msg":"trace[1541306834] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"181.832351ms","start":"2026-04-28T13:50:03.493849Z","end":"2026-04-28T13:50:03.675682Z","steps":["trace[1541306834] 'process raft request' (duration: 181.586666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:03.675836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.633756ms","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-28T13:50:03.675891Z","caller":"traceutil/trace.go:171","msg":"trace[2100562087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1998; }","duration":"166.706427ms","start":"2026-04-28T13:50:03.509157Z","end":"2026-04-28T13:50:03.675864Z","steps":["trace[2100562087] 'agreement among raft nodes before linearized reading' (duration: 166.542624ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:13.826493Z","caller":"traceutil/trace.go:171","msg":"trace[1582879088] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"113.005124ms","start":"2026-04-28T13:50:13.713465Z","end":"2026-04-28T13:50:13.82647Z","steps":["trace[1582879088] 'process raft request' (duration: 112.822738ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:20.157421Z","caller":"traceutil/trace.go:171","msg":"trace[1890743085] linearizableReadLoop","detail":"{readStateIndex:2168; appliedIndex:2167; }","duration":"414.436337ms","start":"2026-04-28T13:50:19.742958Z","end":"2026-04-28T13:50:20.157394Z","steps":["trace[1890743085] 'read index received' (duration: 414.303849ms)","trace[1890743085] 'applied index is now lower than readState.Index' (duration: 131.338µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:20.157517Z","caller":"traceutil/trace.go:171","msg":"trace[1587498122] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"680.436086ms","start":"2026-04-28T13:50:19.477049Z","end":"2026-04-28T13:50:20.157485Z","steps":["trace[1587498122] 'process raft request' (duration: 680.157077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.157621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.656427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:50:20.157664Z","caller":"traceutil/trace.go:171","msg":"trace[217827207] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2091; }","duration":"414.760902ms","start":"2026-04-28T13:50:19.742892Z","end":"2026-04-28T13:50:20.157653Z","steps":["trace[217827207] 'agreement among raft nodes before linearized reading' (duration: 414.639525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.157666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:19.477025Z","time spent":"680.556443ms","remote":"127.0.0.1:43314","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-28T13:50:20.157706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:19.74287Z","time spent":"414.82132ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":56,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:50:20.617922Z","caller":"traceutil/trace.go:171","msg":"trace[2091024123] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2168; }","duration":"460.403966ms","start":"2026-04-28T13:50:20.157497Z","end":"2026-04-28T13:50:20.617901Z","steps":["trace[2091024123] 'read index received' (duration: 460.081831ms)","trace[2091024123] 'applied index is now lower than readState.Index' (duration: 320.935µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:20.61802Z","caller":"traceutil/trace.go:171","msg":"trace[2075940992] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"756.97716ms","start":"2026-04-28T13:50:19.861005Z","end":"2026-04-28T13:50:20.617982Z","steps":["trace[2075940992] 'process raft request' (duration: 756.507673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.618159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.324613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-28T13:50:20.61817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.171102ms","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-28T13:50:20.618191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:19.860976Z","time spent":"757.141051ms","remote":"127.0.0.1:43314","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-28T13:50:20.618223Z","caller":"traceutil/trace.go:171","msg":"trace[1551418996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2094; }","duration":"601.254674ms","start":"2026-04-28T13:50:20.016955Z","end":"2026-04-28T13:50:20.61821Z","steps":["trace[1551418996] 'agreement among raft nodes before linearized reading' (duration: 600.980715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.618242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.738182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:50:20.618261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.016943Z","time spent":"601.309452ms","remote":"127.0.0.1:43314","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-28T13:50:20.618353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.289448ms","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-28T13:50:20.61838Z","caller":"traceutil/trace.go:171","msg":"trace[1233854713] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"754.653614ms","start":"2026-04-28T13:50:19.863713Z","end":"2026-04-28T13:50:20.618367Z","steps":["trace[1233854713] 'process raft request' (duration: 754.113748ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:20.61839Z","caller":"traceutil/trace.go:171","msg":"trace[267040170] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2094; }","duration":"744.362559ms","start":"2026-04-28T13:50:19.87402Z","end":"2026-04-28T13:50:20.618383Z","steps":["trace[267040170] 'agreement among raft nodes before linearized reading' (duration: 744.267205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.618371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.54759ms","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-28T13:50:20.618427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:19.874004Z","time spent":"744.415116ms","remote":"127.0.0.1:43314","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-28T13:50:20.618446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:19.86369Z","time spent":"754.718374ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:20.618463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.458951ms","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-28T13:50:20.618269Z","caller":"traceutil/trace.go:171","msg":"trace[1485703565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2094; }","duration":"743.807071ms","start":"2026-04-28T13:50:19.874455Z","end":"2026-04-28T13:50:20.618262Z","steps":["trace[1485703565] 'agreement among raft nodes before linearized reading' (duration: 743.767626ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:20.618491Z","caller":"traceutil/trace.go:171","msg":"trace[1096891786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2094; }","duration":"609.689261ms","start":"2026-04-28T13:50:20.008758Z","end":"2026-04-28T13:50:20.618448Z","steps":["trace[1096891786] 'agreement among raft nodes before linearized reading' (duration: 609.315208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.618501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.774863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T13:50:20.61851Z","caller":"traceutil/trace.go:171","msg":"trace[273106399] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"703.351327ms","start":"2026-04-28T13:50:19.91514Z","end":"2026-04-28T13:50:20.618491Z","steps":["trace[273106399] 'process raft request' (duration: 702.722299ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:20.618524Z","caller":"traceutil/trace.go:171","msg":"trace[1366524287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2094; }","duration":"604.560025ms","start":"2026-04-28T13:50:20.013949Z","end":"2026-04-28T13:50:20.618509Z","steps":["trace[1366524287] 'agreement among raft nodes before linearized reading' (duration: 604.285467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.618532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:19.874432Z","time spent":"744.093431ms","remote":"127.0.0.1:39966","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-28T13:50:20.618558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.008738Z","time spent":"609.803386ms","remote":"127.0.0.1:43314","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":"info","ts":"2026-04-28T13:50:20.618562Z","caller":"traceutil/trace.go:171","msg":"trace[1634396072] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2094; }","duration":"608.850064ms","start":"2026-04-28T13:50:20.009693Z","end":"2026-04-28T13:50:20.618543Z","steps":["trace[1634396072] 'agreement among raft nodes before linearized reading' (duration: 608.704103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.61857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.01393Z","time spent":"604.630255ms","remote":"127.0.0.1:43314","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-28T13:50:20.618626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:19.915121Z","time spent":"703.436368ms","remote":"127.0.0.1:43314","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-28T13:50:20.618611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.009687Z","time spent":"608.912892ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T13:50:20.618201Z","caller":"traceutil/trace.go:171","msg":"trace[1570281098] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2094; }","duration":"601.405884ms","start":"2026-04-28T13:50:20.016781Z","end":"2026-04-28T13:50:20.618187Z","steps":["trace[1570281098] 'agreement among raft nodes before linearized reading' (duration: 601.23129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.618709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.016767Z","time spent":"601.929497ms","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-28T13:50:20.926485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.82741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:50:20.926865Z","caller":"traceutil/trace.go:171","msg":"trace[1507616355] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"610.247249ms","start":"2026-04-28T13:50:20.316583Z","end":"2026-04-28T13:50:20.92683Z","steps":["trace[1507616355] 'process raft request' (duration: 378.977857ms)","trace[1507616355] 'compare' (duration: 230.706474ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:20.926899Z","caller":"traceutil/trace.go:171","msg":"trace[606171915] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2171; }","duration":"308.842723ms","start":"2026-04-28T13:50:20.617988Z","end":"2026-04-28T13:50:20.92683Z","steps":["trace[606171915] 'read index received' (duration: 77.516213ms)","trace[606171915] 'applied index is now lower than readState.Index' (duration: 231.32354ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:50:20.926959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.960672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:20.927033Z","caller":"traceutil/trace.go:171","msg":"trace[2100414344] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2096; }","duration":"324.095481ms","start":"2026-04-28T13:50:20.602919Z","end":"2026-04-28T13:50:20.927015Z","steps":["trace[2100414344] 'agreement among raft nodes before linearized reading' (duration: 323.957671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.927088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.6029Z","time spent":"324.17264ms","remote":"127.0.0.1:41400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:50:20.927075Z","caller":"traceutil/trace.go:171","msg":"trace[276649868] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"302.861889ms","start":"2026-04-28T13:50:20.624059Z","end":"2026-04-28T13:50:20.926921Z","steps":["trace[276649868] 'process raft request' (duration: 302.547415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.927167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.316558Z","time spent":"610.440856ms","remote":"127.0.0.1:43314","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-28T13:50:20.927296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.768143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T13:50:20.927283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.624034Z","time spent":"303.110603ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:50:20.927403Z","caller":"traceutil/trace.go:171","msg":"trace[1671302818] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"303.218748ms","start":"2026-04-28T13:50:20.624153Z","end":"2026-04-28T13:50:20.927372Z","steps":["trace[1671302818] 'process raft request' (duration: 302.524052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.927565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.42255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:50:20.92769Z","caller":"traceutil/trace.go:171","msg":"trace[1680993881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2096; }","duration":"518.576251ms","start":"2026-04-28T13:50:20.409097Z","end":"2026-04-28T13:50:20.927673Z","steps":["trace[1680993881] 'agreement among raft nodes before linearized reading' (duration: 517.7833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.927568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.624147Z","time spent":"303.339715ms","remote":"127.0.0.1:43314","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-28T13:50:20.927756Z","caller":"traceutil/trace.go:171","msg":"trace[229307602] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"303.44408ms","start":"2026-04-28T13:50:20.624295Z","end":"2026-04-28T13:50:20.927739Z","steps":["trace[229307602] 'process raft request' (duration: 302.920328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.927818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.409079Z","time spent":"518.693848ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T13:50:20.927866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.624286Z","time spent":"303.525672ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:50:20.927394Z","caller":"traceutil/trace.go:171","msg":"trace[1706055580] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2096; }","duration":"665.908352ms","start":"2026-04-28T13:50:20.261467Z","end":"2026-04-28T13:50:20.927375Z","steps":["trace[1706055580] 'agreement among raft nodes before linearized reading' (duration: 665.524489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.927948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.261446Z","time spent":"666.479912ms","remote":"127.0.0.1:43164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":9,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:50:20.928246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.900212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:20.928315Z","caller":"traceutil/trace.go:171","msg":"trace[787178450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2100; }","duration":"307.001817ms","start":"2026-04-28T13:50:20.621297Z","end":"2026-04-28T13:50:20.928299Z","steps":["trace[787178450] 'agreement among raft nodes before linearized reading' (duration: 306.88013ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:20.928528Z","caller":"traceutil/trace.go:171","msg":"trace[1140985483] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"303.786268ms","start":"2026-04-28T13:50:20.624727Z","end":"2026-04-28T13:50:20.928513Z","steps":["trace[1140985483] 'process raft request' (duration: 302.937019ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:20.928631Z","caller":"traceutil/trace.go:171","msg":"trace[1575991215] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"303.672232ms","start":"2026-04-28T13:50:20.624942Z","end":"2026-04-28T13:50:20.928614Z","steps":["trace[1575991215] 'process raft request' (duration: 303.050125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:20.928556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.621281Z","time spent":"307.258222ms","remote":"127.0.0.1:39960","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-28T13:50:20.928723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.624929Z","time spent":"303.751693ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:20.928644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:20.624715Z","time spent":"303.841576ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:22.487266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.84078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:22.487336Z","caller":"traceutil/trace.go:171","msg":"trace[266762806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2102; }","duration":"610.957645ms","start":"2026-04-28T13:50:21.876364Z","end":"2026-04-28T13:50:22.487322Z","steps":["trace[266762806] 'range keys from in-memory index tree' (duration: 610.742265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:22.487371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:21.876339Z","time spent":"611.024865ms","remote":"127.0.0.1:39960","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-28T13:50:22.487915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.409466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-04-28T13:50:22.487994Z","caller":"traceutil/trace.go:171","msg":"trace[1093950555] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2102; }","duration":"595.516191ms","start":"2026-04-28T13:50:21.892462Z","end":"2026-04-28T13:50:22.487978Z","steps":["trace[1093950555] 'range keys from in-memory index tree' (duration: 595.159521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:22.488046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:21.89245Z","time spent":"595.57679ms","remote":"127.0.0.1:43230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12408,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-28T13:50:22.876348Z","caller":"traceutil/trace.go:171","msg":"trace[1794396432] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"249.956814ms","start":"2026-04-28T13:50:22.626366Z","end":"2026-04-28T13:50:22.876323Z","steps":["trace[1794396432] 'process raft request' (duration: 249.818185ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:23.006397Z","caller":"traceutil/trace.go:171","msg":"trace[1192767030] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"379.438952ms","start":"2026-04-28T13:50:22.62693Z","end":"2026-04-28T13:50:23.006369Z","steps":["trace[1192767030] 'process raft request' (duration: 379.310904ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:50:23.006469Z","caller":"traceutil/trace.go:171","msg":"trace[1528300452] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"379.867042ms","start":"2026-04-28T13:50:22.626531Z","end":"2026-04-28T13:50:23.006398Z","steps":["trace[1528300452] 'process raft request' (duration: 379.547898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:23.006553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:22.626902Z","time spent":"379.572011ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:50:23.006758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:22.626517Z","time spent":"380.07164ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:50:23.007214Z","caller":"traceutil/trace.go:171","msg":"trace[1306458746] linearizableReadLoop","detail":"{readStateIndex:2182; appliedIndex:2180; }","duration":"371.904132ms","start":"2026-04-28T13:50:22.63529Z","end":"2026-04-28T13:50:23.007194Z","steps":["trace[1306458746] 'read index received' (duration: 241.181469ms)","trace[1306458746] 'applied index is now lower than readState.Index' (duration: 130.720663ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:50:23.00757Z","caller":"traceutil/trace.go:171","msg":"trace[1347044758] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"223.2585ms","start":"2026-04-28T13:50:22.784251Z","end":"2026-04-28T13:50:23.007509Z","steps":["trace[1347044758] 'process raft request' (duration: 222.03589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:23.007827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.46662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-28T13:50:23.00791Z","caller":"traceutil/trace.go:171","msg":"trace[1618628087] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2106; }","duration":"372.634764ms","start":"2026-04-28T13:50:22.635257Z","end":"2026-04-28T13:50:23.007892Z","steps":["trace[1618628087] 'agreement among raft nodes before linearized reading' (duration: 372.247309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:50:23.008007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:50:22.635245Z","time spent":"372.74605ms","remote":"127.0.0.1:40884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-28T13:50:23.009246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.43379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:50:23.009459Z","caller":"traceutil/trace.go:171","msg":"trace[335310211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2106; }","duration":"134.682154ms","start":"2026-04-28T13:50:22.874764Z","end":"2026-04-28T13:50:23.009446Z","steps":["trace[335310211] 'agreement among raft nodes before linearized reading' (duration: 133.725641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:26.955848Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.609355861s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T13:51:27.502629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.394464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:51:27.502874Z","caller":"traceutil/trace.go:171","msg":"trace[1608493235] linearizableReadLoop","detail":"{readStateIndex:2603; appliedIndex:2601; }","duration":"2.157268316s","start":"2026-04-28T13:51:25.345588Z","end":"2026-04-28T13:51:27.502857Z","steps":["trace[1608493235] 'read index received' (duration: 1.021903ms)","trace[1608493235] 'applied index is now lower than readState.Index' (duration: 2.156245462s)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:51:27.502906Z","caller":"traceutil/trace.go:171","msg":"trace[1560082350] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"206.411352ms","start":"2026-04-28T13:51:27.29647Z","end":"2026-04-28T13:51:27.502881Z","steps":["trace[1560082350] 'process raft request' (duration: 206.284713ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:27.502968Z","caller":"traceutil/trace.go:171","msg":"trace[1382115217] transaction","detail":"{read_only:false; response_revision:2511; number_of_response:1; }","duration":"2.15852562s","start":"2026-04-28T13:51:25.344433Z","end":"2026-04-28T13:51:27.502959Z","steps":["trace[1382115217] 'process raft request' (duration: 1.611705487s)","trace[1382115217] 'compare' (duration: 546.21865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:51:27.503048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:25.344407Z","time spent":"2.158579269s","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:51:27.503265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.157066388s","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-28T13:51:27.503289Z","caller":"traceutil/trace.go:171","msg":"trace[146186750] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2514; }","duration":"2.157089541s","start":"2026-04-28T13:51:25.346193Z","end":"2026-04-28T13:51:27.503282Z","steps":["trace[146186750] 'agreement among raft nodes before linearized reading' (duration: 2.157044285s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:27.503286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.157676353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-28T13:51:27.503321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:25.346183Z","time spent":"2.157133387s","remote":"127.0.0.1:43510","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":"info","ts":"2026-04-28T13:51:27.503428Z","caller":"traceutil/trace.go:171","msg":"trace[649930545] transaction","detail":"{read_only:false; response_revision:2513; number_of_response:1; }","duration":"192.857985ms","start":"2026-04-28T13:51:27.310561Z","end":"2026-04-28T13:51:27.503419Z","steps":["trace[649930545] 'process raft request' (duration: 192.274433ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:27.503492Z","caller":"traceutil/trace.go:171","msg":"trace[718622020] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"168.476061ms","start":"2026-04-28T13:51:27.335011Z","end":"2026-04-28T13:51:27.503488Z","steps":["trace[718622020] 'process raft request' (duration: 168.174978ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:27.503517Z","caller":"traceutil/trace.go:171","msg":"trace[1434684340] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2513; }","duration":"2.157771397s","start":"2026-04-28T13:51:25.345562Z","end":"2026-04-28T13:51:27.503333Z","steps":["trace[1434684340] 'agreement among raft nodes before linearized reading' (duration: 2.157564878s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:27.503601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:25.345551Z","time spent":"2.15802051s","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T13:51:27.503665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.686709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:51:27.503729Z","caller":"traceutil/trace.go:171","msg":"trace[312585420] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2514; }","duration":"255.779431ms","start":"2026-04-28T13:51:27.247945Z","end":"2026-04-28T13:51:27.503724Z","steps":["trace[312585420] 'agreement among raft nodes before linearized reading' (duration: 255.680699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:27.503863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.311162ms","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-28T13:51:27.503883Z","caller":"traceutil/trace.go:171","msg":"trace[1479377315] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2514; }","duration":"285.395794ms","start":"2026-04-28T13:51:27.218482Z","end":"2026-04-28T13:51:27.503877Z","steps":["trace[1479377315] 'agreement among raft nodes before linearized reading' (duration: 285.36038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:27.504062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.148189372s","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-28T13:51:27.504086Z","caller":"traceutil/trace.go:171","msg":"trace[1295383535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2514; }","duration":"2.148237229s","start":"2026-04-28T13:51:25.355843Z","end":"2026-04-28T13:51:27.50408Z","steps":["trace[1295383535] 'agreement among raft nodes before linearized reading' (duration: 2.148168129s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:27.504123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:25.355831Z","time spent":"2.148285935s","remote":"127.0.0.1:43314","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-28T13:51:27.50411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.146438639s","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-28T13:51:27.50416Z","caller":"traceutil/trace.go:171","msg":"trace[571272057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2514; }","duration":"2.146515818s","start":"2026-04-28T13:51:25.357632Z","end":"2026-04-28T13:51:27.504148Z","steps":["trace[571272057] 'agreement among raft nodes before linearized reading' (duration: 2.146374049s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:27.50419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:25.357622Z","time spent":"2.146562406s","remote":"127.0.0.1:43314","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-28T13:51:27.504243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.282411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:51:27.504259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121568363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/\" range_end:\"/registry/secrets/auth-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:51:27.504282Z","caller":"traceutil/trace.go:171","msg":"trace[371371288] range","detail":"{range_begin:/registry/secrets/auth-system/; range_end:/registry/secrets/auth-system0; response_count:0; response_revision:2514; }","duration":"1.121585756s","start":"2026-04-28T13:51:26.382685Z","end":"2026-04-28T13:51:27.504271Z","steps":["trace[371371288] 'agreement among raft nodes before linearized reading' (duration: 1.121553311s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:27.504303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:26.382668Z","time spent":"1.121630862s","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/auth-system/\" range_end:\"/registry/secrets/auth-system0\" "} {"level":"info","ts":"2026-04-28T13:51:27.504275Z","caller":"traceutil/trace.go:171","msg":"trace[1338746914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2514; }","duration":"554.498221ms","start":"2026-04-28T13:51:26.949769Z","end":"2026-04-28T13:51:27.504267Z","steps":["trace[1338746914] 'agreement among raft nodes before linearized reading' (duration: 554.318837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:27.504379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:26.949741Z","time spent":"554.629319ms","remote":"127.0.0.1:39966","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-28T13:51:27.504598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.08672ms","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":"warn","ts":"2026-04-28T13:51:27.504625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.146829883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T13:51:27.504676Z","caller":"traceutil/trace.go:171","msg":"trace[1850845722] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2514; }","duration":"348.278347ms","start":"2026-04-28T13:51:27.156387Z","end":"2026-04-28T13:51:27.504665Z","steps":["trace[1850845722] 'agreement among raft nodes before linearized reading' (duration: 348.035623ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:51:27.504712Z","caller":"traceutil/trace.go:171","msg":"trace[53160575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2514; }","duration":"2.146937857s","start":"2026-04-28T13:51:25.357755Z","end":"2026-04-28T13:51:27.504693Z","steps":["trace[53160575] 'agreement among raft nodes before linearized reading' (duration: 2.146627355s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:51:27.504738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:27.156373Z","time spent":"348.352798ms","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:51:27.504774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:51:25.357749Z","time spent":"2.147010738s","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T13:52:05.390335Z","caller":"traceutil/trace.go:171","msg":"trace[2146200490] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"125.293836ms","start":"2026-04-28T13:52:05.265014Z","end":"2026-04-28T13:52:05.390307Z","steps":["trace[2146200490] 'process raft request' (duration: 125.119812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.734698Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.422839535s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T13:52:07.736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424051904s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:52:07.736188Z","caller":"traceutil/trace.go:171","msg":"trace[1654160125] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"1.534480311s","start":"2026-04-28T13:52:06.201693Z","end":"2026-04-28T13:52:07.736173Z","steps":["trace[1654160125] 'process raft request' (duration: 1.534444207s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:07.736249Z","caller":"traceutil/trace.go:171","msg":"trace[1377288888] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"1.871271321s","start":"2026-04-28T13:52:05.864965Z","end":"2026-04-28T13:52:07.736237Z","steps":["trace[1377288888] 'process raft request' (duration: 446.926045ms)","trace[1377288888] 'compare' (duration: 1.423958401s)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:07.736286Z","caller":"traceutil/trace.go:171","msg":"trace[34694581] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"1.647523502s","start":"2026-04-28T13:52:06.088755Z","end":"2026-04-28T13:52:07.736279Z","steps":["trace[34694581] 'process raft request' (duration: 1.647335006s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.736311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:05.864929Z","time spent":"1.871355052s","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:52:07.736334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:06.088725Z","time spent":"1.647585311s","remote":"127.0.0.1:43216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9266,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:52:07.736426Z","caller":"traceutil/trace.go:171","msg":"trace[1836224390] linearizableReadLoop","detail":"{readStateIndex:2862; appliedIndex:2861; }","duration":"1.863410386s","start":"2026-04-28T13:52:05.873007Z","end":"2026-04-28T13:52:07.736417Z","steps":["trace[1836224390] 'read index received' (duration: 438.898438ms)","trace[1836224390] 'applied index is now lower than readState.Index' (duration: 1.424510798s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:07.736252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:06.201672Z","time spent":"1.534551831s","remote":"127.0.0.1:43314","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-28T13:52:07.736675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.863662991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:07.736711Z","caller":"traceutil/trace.go:171","msg":"trace[921575981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2763; }","duration":"1.86372386s","start":"2026-04-28T13:52:05.872977Z","end":"2026-04-28T13:52:07.736701Z","steps":["trace[921575981] 'agreement among raft nodes before linearized reading' (duration: 1.863665781s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.736737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:05.872959Z","time spent":"1.863772476s","remote":"127.0.0.1:39966","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-28T13:52:07.767077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718300533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:52:07.76712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714018256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:52:07.767174Z","caller":"traceutil/trace.go:171","msg":"trace[1937351556] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2764; }","duration":"1.714111109s","start":"2026-04-28T13:52:06.053051Z","end":"2026-04-28T13:52:07.767162Z","steps":["trace[1937351556] 'agreement among raft nodes before linearized reading' (duration: 1.713931364s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:07.767168Z","caller":"traceutil/trace.go:171","msg":"trace[1525230159] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2764; }","duration":"1.718434541s","start":"2026-04-28T13:52:06.048712Z","end":"2026-04-28T13:52:07.767147Z","steps":["trace[1525230159] 'agreement among raft nodes before linearized reading' (duration: 1.718274128s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.7672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:06.05304Z","time spent":"1.714154775s","remote":"127.0.0.1:43404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":90,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:52:07.767212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:06.048689Z","time spent":"1.718512831s","remote":"127.0.0.1:41418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:52:07.767418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.880974741s","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-28T13:52:07.767469Z","caller":"traceutil/trace.go:171","msg":"trace[1695066354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2764; }","duration":"1.881034258s","start":"2026-04-28T13:52:05.886422Z","end":"2026-04-28T13:52:07.767456Z","steps":["trace[1695066354] 'agreement among raft nodes before linearized reading' (duration: 1.880871576s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.767491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.881120732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-28T13:52:07.767503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:05.886417Z","time spent":"1.881077825s","remote":"127.0.0.1:43314","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-28T13:52:07.76753Z","caller":"traceutil/trace.go:171","msg":"trace[148184097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2764; }","duration":"1.881166628s","start":"2026-04-28T13:52:05.886353Z","end":"2026-04-28T13:52:07.76752Z","steps":["trace[148184097] 'agreement among raft nodes before linearized reading' (duration: 1.881072544s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.767564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:05.886348Z","time spent":"1.881207332s","remote":"127.0.0.1:43314","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-28T13:52:07.767583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.401959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:52:07.76763Z","caller":"traceutil/trace.go:171","msg":"trace[356154101] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2764; }","duration":"367.4809ms","start":"2026-04-28T13:52:07.400139Z","end":"2026-04-28T13:52:07.76762Z","steps":["trace[356154101] 'agreement among raft nodes before linearized reading' (duration: 367.422571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.767648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:07.400117Z","time spent":"367.525516ms","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:52:07.767657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.84519623s","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-28T13:52:07.767694Z","caller":"traceutil/trace.go:171","msg":"trace[85713089] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2764; }","duration":"1.845258351s","start":"2026-04-28T13:52:05.922423Z","end":"2026-04-28T13:52:07.767682Z","steps":["trace[85713089] 'agreement among raft nodes before linearized reading' (duration: 1.845203583s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.76773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.883107007s","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-28T13:52:07.767764Z","caller":"traceutil/trace.go:171","msg":"trace[1049101077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2764; }","duration":"1.883188648s","start":"2026-04-28T13:52:05.884567Z","end":"2026-04-28T13:52:07.767756Z","steps":["trace[1049101077] 'agreement among raft nodes before linearized reading' (duration: 1.883106357s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.767795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:05.884551Z","time spent":"1.883237826s","remote":"127.0.0.1:43314","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-28T13:52:07.76781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.881311718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T13:52:07.767836Z","caller":"traceutil/trace.go:171","msg":"trace[1648218860] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2764; }","duration":"1.881361145s","start":"2026-04-28T13:52:05.886468Z","end":"2026-04-28T13:52:07.767829Z","steps":["trace[1648218860] 'agreement among raft nodes before linearized reading' (duration: 1.881283024s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.76787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:05.886453Z","time spent":"1.881409611s","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T13:52:07.767908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.894650913s","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-28T13:52:07.767938Z","caller":"traceutil/trace.go:171","msg":"trace[1597947028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2764; }","duration":"1.894693189s","start":"2026-04-28T13:52:05.873237Z","end":"2026-04-28T13:52:07.76793Z","steps":["trace[1597947028] 'agreement among raft nodes before linearized reading' (duration: 1.894621559s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.767962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:05.87323Z","time spent":"1.894726553s","remote":"127.0.0.1:43314","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-28T13:52:07.768006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402058113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-28T13:52:07.768031Z","caller":"traceutil/trace.go:171","msg":"trace[1306457538] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2764; }","duration":"1.402126013s","start":"2026-04-28T13:52:06.365899Z","end":"2026-04-28T13:52:07.768025Z","steps":["trace[1306457538] 'agreement among raft nodes before linearized reading' (duration: 1.401994574s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.768046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:06.365853Z","time spent":"1.402190203s","remote":"127.0.0.1:43234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-28T13:52:07.767733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:05.922407Z","time spent":"1.845317748s","remote":"127.0.0.1:40720","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":"warn","ts":"2026-04-28T13:52:07.767879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211292775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:52:07.768253Z","caller":"traceutil/trace.go:171","msg":"trace[918193581] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2764; }","duration":"1.211692361s","start":"2026-04-28T13:52:06.556534Z","end":"2026-04-28T13:52:07.768226Z","steps":["trace[918193581] 'agreement among raft nodes before linearized reading' (duration: 1.211206703s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:07.76834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:06.556515Z","time spent":"1.211797266s","remote":"127.0.0.1:43300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:55:45.214546Z","caller":"traceutil/trace.go:171","msg":"trace[979798052] transaction","detail":"{read_only:false; response_revision:4101; number_of_response:1; }","duration":"105.352282ms","start":"2026-04-28T13:55:45.10917Z","end":"2026-04-28T13:55:45.214522Z","steps":["trace[979798052] 'process raft request' (duration: 105.203232ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:53.397226Z","caller":"traceutil/trace.go:171","msg":"trace[512427586] transaction","detail":"{read_only:false; response_revision:4486; number_of_response:1; }","duration":"132.496728ms","start":"2026-04-28T13:55:53.2647Z","end":"2026-04-28T13:55:53.397197Z","steps":["trace[512427586] 'process raft request' (duration: 132.338457ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:53.473826Z","caller":"traceutil/trace.go:171","msg":"trace[1508272368] transaction","detail":"{read_only:false; response_revision:4487; number_of_response:1; }","duration":"208.331356ms","start":"2026-04-28T13:55:53.265465Z","end":"2026-04-28T13:55:53.473797Z","steps":["trace[1508272368] 'process raft request' (duration: 159.801816ms)","trace[1508272368] 'compare' (duration: 48.293394ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:53.474286Z","caller":"traceutil/trace.go:171","msg":"trace[868021265] transaction","detail":"{read_only:false; response_revision:4488; number_of_response:1; }","duration":"208.270059ms","start":"2026-04-28T13:55:53.265979Z","end":"2026-04-28T13:55:53.474249Z","steps":["trace[868021265] 'process raft request' (duration: 207.739802ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:53.474392Z","caller":"traceutil/trace.go:171","msg":"trace[1443753549] transaction","detail":"{read_only:false; response_revision:4489; number_of_response:1; }","duration":"207.384443ms","start":"2026-04-28T13:55:53.266997Z","end":"2026-04-28T13:55:53.474382Z","steps":["trace[1443753549] 'process raft request' (duration: 207.210733ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:55.723507Z","caller":"traceutil/trace.go:171","msg":"trace[1545596430] transaction","detail":"{read_only:false; response_revision:4502; number_of_response:1; }","duration":"164.337683ms","start":"2026-04-28T13:55:55.559145Z","end":"2026-04-28T13:55:55.723483Z","steps":["trace[1545596430] 'process raft request' (duration: 164.293075ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:55.723992Z","caller":"traceutil/trace.go:171","msg":"trace[1033808610] transaction","detail":"{read_only:false; response_revision:4501; number_of_response:1; }","duration":"164.87779ms","start":"2026-04-28T13:55:55.559098Z","end":"2026-04-28T13:55:55.723976Z","steps":["trace[1033808610] 'process raft request' (duration: 164.185661ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:06.157798Z","caller":"traceutil/trace.go:171","msg":"trace[1730679143] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"122.083486ms","start":"2026-04-28T13:56:06.035659Z","end":"2026-04-28T13:56:06.157742Z","steps":["trace[1730679143] 'process raft request' (duration: 121.770482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:06.545493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.435729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0ed6f638-2715-4821-85a2-71b925bbad03\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-28T13:56:06.545589Z","caller":"traceutil/trace.go:171","msg":"trace[1209995356] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0ed6f638-2715-4821-85a2-71b925bbad03; range_end:; response_count:1; response_revision:4739; }","duration":"254.562104ms","start":"2026-04-28T13:56:06.291006Z","end":"2026-04-28T13:56:06.545568Z","steps":["trace[1209995356] 'range keys from in-memory index tree' (duration: 254.245969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:06.545662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.022071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0ed6f638-2715-4821-85a2-71b925bbad03\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-28T13:56:06.545739Z","caller":"traceutil/trace.go:171","msg":"trace[2003843883] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0ed6f638-2715-4821-85a2-71b925bbad03; range_end:; response_count:1; response_revision:4739; }","duration":"228.145735ms","start":"2026-04-28T13:56:06.317576Z","end":"2026-04-28T13:56:06.545721Z","steps":["trace[2003843883] 'range keys from in-memory index tree' (duration: 227.8455ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:06.659011Z","caller":"traceutil/trace.go:171","msg":"trace[1977125259] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"105.252289ms","start":"2026-04-28T13:56:06.553739Z","end":"2026-04-28T13:56:06.658992Z","steps":["trace[1977125259] 'process raft request' (duration: 105.134055ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:10.454677Z","caller":"traceutil/trace.go:171","msg":"trace[1556755206] linearizableReadLoop","detail":"{readStateIndex:4987; appliedIndex:4986; }","duration":"129.381933ms","start":"2026-04-28T13:56:10.325265Z","end":"2026-04-28T13:56:10.454647Z","steps":["trace[1556755206] 'read index received' (duration: 129.161554ms)","trace[1556755206] 'applied index is now lower than readState.Index' (duration: 219.019µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:10.454702Z","caller":"traceutil/trace.go:171","msg":"trace[71941772] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"165.190517ms","start":"2026-04-28T13:56:10.28948Z","end":"2026-04-28T13:56:10.454671Z","steps":["trace[71941772] 'process raft request' (duration: 164.947659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:10.454941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.652189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-28z8r\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-04-28T13:56:10.455006Z","caller":"traceutil/trace.go:171","msg":"trace[1811598405] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-28z8r; range_end:; response_count:1; response_revision:4807; }","duration":"129.781623ms","start":"2026-04-28T13:56:10.325208Z","end":"2026-04-28T13:56:10.45499Z","steps":["trace[1811598405] 'agreement among raft nodes before linearized reading' (duration: 129.538715ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:10.596795Z","caller":"traceutil/trace.go:171","msg":"trace[1128738227] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"270.291889ms","start":"2026-04-28T13:56:10.326455Z","end":"2026-04-28T13:56:10.596747Z","steps":["trace[1128738227] 'process raft request' (duration: 268.20167ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:10.59888Z","caller":"traceutil/trace.go:171","msg":"trace[1972935605] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"139.945093ms","start":"2026-04-28T13:56:10.458902Z","end":"2026-04-28T13:56:10.598847Z","steps":["trace[1972935605] 'process raft request' (duration: 139.80584ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:10.599015Z","caller":"traceutil/trace.go:171","msg":"trace[1551586963] linearizableReadLoop","detail":"{readStateIndex:4990; appliedIndex:4987; }","duration":"109.516671ms","start":"2026-04-28T13:56:10.489308Z","end":"2026-04-28T13:56:10.598825Z","steps":["trace[1551586963] 'read index received' (duration: 105.363181ms)","trace[1551586963] 'applied index is now lower than readState.Index' (duration: 4.15263ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:10.598945Z","caller":"traceutil/trace.go:171","msg":"trace[416998422] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"132.668655ms","start":"2026-04-28T13:56:10.46626Z","end":"2026-04-28T13:56:10.598929Z","steps":["trace[416998422] 'process raft request' (duration: 132.519561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:10.599293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.975538ms","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-28T13:56:10.599324Z","caller":"traceutil/trace.go:171","msg":"trace[1907554898] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4810; }","duration":"110.061263ms","start":"2026-04-28T13:56:10.489253Z","end":"2026-04-28T13:56:10.599314Z","steps":["trace[1907554898] 'agreement among raft nodes before linearized reading' (duration: 109.787597ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.612533Z","caller":"traceutil/trace.go:171","msg":"trace[1467011361] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"235.15544ms","start":"2026-04-28T13:56:16.377348Z","end":"2026-04-28T13:56:16.612504Z","steps":["trace[1467011361] 'process raft request' (duration: 234.999526ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:16.990333Z","caller":"traceutil/trace.go:171","msg":"trace[2125672071] linearizableReadLoop","detail":"{readStateIndex:5041; appliedIndex:5039; }","duration":"564.050759ms","start":"2026-04-28T13:56:16.426231Z","end":"2026-04-28T13:56:16.990282Z","steps":["trace[2125672071] 'read index received' (duration: 186.13933ms)","trace[2125672071] 'applied index is now lower than readState.Index' (duration: 377.910189ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:16.990387Z","caller":"traceutil/trace.go:171","msg":"trace[2032984550] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"605.759224ms","start":"2026-04-28T13:56:16.384582Z","end":"2026-04-28T13:56:16.990341Z","steps":["trace[2032984550] 'process raft request' (duration: 593.412616ms)","trace[2032984550] 'compare' (duration: 12.025172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:16.9905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:16.384567Z","time spent":"605.86853ms","remote":"127.0.0.1:43230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13276,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:16.990574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.293647ms","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-28T13:56:16.990637Z","caller":"traceutil/trace.go:171","msg":"trace[203163847] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4860; }","duration":"564.419551ms","start":"2026-04-28T13:56:16.426185Z","end":"2026-04-28T13:56:16.990605Z","steps":["trace[203163847] 'agreement among raft nodes before linearized reading' (duration: 564.252198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:16.990731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:16.426169Z","time spent":"564.522568ms","remote":"127.0.0.1:43216","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-28T13:56:17.05062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.790633ms","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":"warn","ts":"2026-04-28T13:56:17.050664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.691814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:17.050764Z","caller":"traceutil/trace.go:171","msg":"trace[1245075515] 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:4861; }","duration":"583.891829ms","start":"2026-04-28T13:56:16.466797Z","end":"2026-04-28T13:56:17.050688Z","steps":["trace[1245075515] 'agreement among raft nodes before linearized reading' (duration: 583.6273ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:17.050769Z","caller":"traceutil/trace.go:171","msg":"trace[480353852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4861; }","duration":"175.848236ms","start":"2026-04-28T13:56:16.874902Z","end":"2026-04-28T13:56:17.050751Z","steps":["trace[480353852] 'agreement among raft nodes before linearized reading' (duration: 175.624066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:17.050811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:16.466778Z","time spent":"584.022574ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-28T13:56:17.120444Z","caller":"traceutil/trace.go:171","msg":"trace[238825034] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"124.118336ms","start":"2026-04-28T13:56:16.996311Z","end":"2026-04-28T13:56:17.12043Z","steps":["trace[238825034] 'process raft request' (duration: 124.078179ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:17.12062Z","caller":"traceutil/trace.go:171","msg":"trace[1434178295] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"125.212229ms","start":"2026-04-28T13:56:16.995397Z","end":"2026-04-28T13:56:17.120609Z","steps":["trace[1434178295] 'process raft request' (duration: 98.084556ms)","trace[1434178295] 'compare' (duration: 26.73632ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:17.120701Z","caller":"traceutil/trace.go:171","msg":"trace[1609974280] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"124.7453ms","start":"2026-04-28T13:56:16.995951Z","end":"2026-04-28T13:56:17.120696Z","steps":["trace[1609974280] 'process raft request' (duration: 124.372886ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:17.120717Z","caller":"traceutil/trace.go:171","msg":"trace[781418089] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"115.938363ms","start":"2026-04-28T13:56:17.004767Z","end":"2026-04-28T13:56:17.120706Z","steps":["trace[781418089] 'process raft request' (duration: 115.643025ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:17.120812Z","caller":"traceutil/trace.go:171","msg":"trace[1895098210] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"124.551318ms","start":"2026-04-28T13:56:16.996255Z","end":"2026-04-28T13:56:17.120807Z","steps":["trace[1895098210] 'process raft request' (duration: 124.095449ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:17.120821Z","caller":"traceutil/trace.go:171","msg":"trace[872664116] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"115.53483ms","start":"2026-04-28T13:56:17.005278Z","end":"2026-04-28T13:56:17.120813Z","steps":["trace[872664116] 'process raft request' (duration: 115.183456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:17.422272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.678739ms","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-28T13:56:17.422351Z","caller":"traceutil/trace.go:171","msg":"trace[1455374253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4869; }","duration":"116.816304ms","start":"2026-04-28T13:56:17.305517Z","end":"2026-04-28T13:56:17.422333Z","steps":["trace[1455374253] 'range keys from in-memory index tree' (duration: 116.480269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:17.422645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.30408ms","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-28T13:56:17.422681Z","caller":"traceutil/trace.go:171","msg":"trace[1852931694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4869; }","duration":"132.386426ms","start":"2026-04-28T13:56:17.290285Z","end":"2026-04-28T13:56:17.422671Z","steps":["trace[1852931694] 'range keys from in-memory index tree' (duration: 132.182384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:17.715884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.777071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:17.716133Z","caller":"traceutil/trace.go:171","msg":"trace[1197564129] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"287.93776ms","start":"2026-04-28T13:56:17.428169Z","end":"2026-04-28T13:56:17.716107Z","steps":["trace[1197564129] 'process raft request' (duration: 140.846493ms)","trace[1197564129] 'compare' (duration: 146.424027ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:17.716169Z","caller":"traceutil/trace.go:171","msg":"trace[651486776] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5050; }","duration":"287.167943ms","start":"2026-04-28T13:56:17.428979Z","end":"2026-04-28T13:56:17.716147Z","steps":["trace[651486776] 'read index received' (duration: 36.742898ms)","trace[651486776] 'applied index is now lower than readState.Index' (duration: 250.423705ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:17.716472Z","caller":"traceutil/trace.go:171","msg":"trace[1840833794] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"279.10144ms","start":"2026-04-28T13:56:17.437356Z","end":"2026-04-28T13:56:17.716457Z","steps":["trace[1840833794] 'process raft request' (duration: 278.65554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:17.716691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.69226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-87f859fe-f4af-4169-8145-4209462cf0e6\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-28T13:56:17.716743Z","caller":"traceutil/trace.go:171","msg":"trace[1293104344] range","detail":"{range_begin:/registry/persistentvolumes/pvc-87f859fe-f4af-4169-8145-4209462cf0e6; range_end:; response_count:1; response_revision:4872; }","duration":"287.770068ms","start":"2026-04-28T13:56:17.428958Z","end":"2026-04-28T13:56:17.716728Z","steps":["trace[1293104344] 'agreement among raft nodes before linearized reading' (duration: 287.656543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.11464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.616609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:18.115486Z","caller":"traceutil/trace.go:171","msg":"trace[1352226217] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"392.304244ms","start":"2026-04-28T13:56:17.723149Z","end":"2026-04-28T13:56:18.115454Z","steps":["trace[1352226217] 'process raft request' (duration: 128.762236ms)","trace[1352226217] 'compare' (duration: 262.35124ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:18.115673Z","caller":"traceutil/trace.go:171","msg":"trace[1035059417] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"392.226728ms","start":"2026-04-28T13:56:17.723426Z","end":"2026-04-28T13:56:18.115653Z","steps":["trace[1035059417] 'process raft request' (duration: 391.987138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.115665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:17.723135Z","time spent":"392.427229ms","remote":"127.0.0.1:43208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1272,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:18.115821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:17.723419Z","time spent":"392.326724ms","remote":"127.0.0.1:43322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1392,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:18.115906Z","caller":"traceutil/trace.go:171","msg":"trace[862690903] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"391.453452ms","start":"2026-04-28T13:56:17.724436Z","end":"2026-04-28T13:56:18.11589Z","steps":["trace[862690903] 'process raft request' (duration: 391.092398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.116022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:17.724409Z","time spent":"391.558668ms","remote":"127.0.0.1:43322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:18.11611Z","caller":"traceutil/trace.go:171","msg":"trace[1640489179] linearizableReadLoop","detail":"{readStateIndex:5056; appliedIndex:5053; }","duration":"392.147252ms","start":"2026-04-28T13:56:17.723956Z","end":"2026-04-28T13:56:18.116103Z","steps":["trace[1640489179] 'read index received' (duration: 57.969871ms)","trace[1640489179] 'applied index is now lower than readState.Index' (duration: 334.176701ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:18.116166Z","caller":"traceutil/trace.go:171","msg":"trace[1780856036] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"391.510831ms","start":"2026-04-28T13:56:17.724651Z","end":"2026-04-28T13:56:18.116162Z","steps":["trace[1780856036] 'process raft request' (duration: 391.138136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.116197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:17.724628Z","time spent":"391.552678ms","remote":"127.0.0.1:43208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:18.116382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.38417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.12\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-28T13:56:18.116405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.152179ms","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-28T13:56:18.116434Z","caller":"traceutil/trace.go:171","msg":"trace[989248145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4877; }","duration":"144.216917ms","start":"2026-04-28T13:56:17.972209Z","end":"2026-04-28T13:56:18.116426Z","steps":["trace[989248145] 'agreement among raft nodes before linearized reading' (duration: 144.127441ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:18.116433Z","caller":"traceutil/trace.go:171","msg":"trace[979457510] range","detail":"{range_begin:/registry/masterleases/199.19.213.12; range_end:; response_count:1; response_revision:4877; }","duration":"361.437277ms","start":"2026-04-28T13:56:17.754983Z","end":"2026-04-28T13:56:18.116421Z","steps":["trace[979457510] 'agreement among raft nodes before linearized reading' (duration: 361.264735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.116475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:17.754926Z","time spent":"361.537294ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.12\" "} {"level":"warn","ts":"2026-04-28T13:56:18.116584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.211723ms","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-28T13:56:18.116607Z","caller":"traceutil/trace.go:171","msg":"trace[1170514583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4877; }","duration":"138.257779ms","start":"2026-04-28T13:56:17.978344Z","end":"2026-04-28T13:56:18.116602Z","steps":["trace[1170514583] 'agreement among raft nodes before linearized reading' (duration: 138.189952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.116657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.689767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-75a42d28-2f4b-4723-a84a-f15e5234b6e7\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-28T13:56:18.116741Z","caller":"traceutil/trace.go:171","msg":"trace[349132184] range","detail":"{range_begin:/registry/persistentvolumes/pvc-75a42d28-2f4b-4723-a84a-f15e5234b6e7; range_end:; response_count:1; response_revision:4877; }","duration":"392.805143ms","start":"2026-04-28T13:56:17.72392Z","end":"2026-04-28T13:56:18.116726Z","steps":["trace[349132184] 'agreement among raft nodes before linearized reading' (duration: 392.447389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.116812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:17.723907Z","time spent":"392.8918ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1458,"request content":"key:\"/registry/persistentvolumes/pvc-75a42d28-2f4b-4723-a84a-f15e5234b6e7\" "} {"level":"warn","ts":"2026-04-28T13:56:18.116904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.363127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-28T13:56:18.11703Z","caller":"traceutil/trace.go:171","msg":"trace[1315681362] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4877; }","duration":"251.533249ms","start":"2026-04-28T13:56:17.865421Z","end":"2026-04-28T13:56:18.116954Z","steps":["trace[1315681362] 'agreement among raft nodes before linearized reading' (duration: 250.964784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.116696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.384644ms","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-28T13:56:18.117189Z","caller":"traceutil/trace.go:171","msg":"trace[1683019590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4877; }","duration":"353.90099ms","start":"2026-04-28T13:56:17.763274Z","end":"2026-04-28T13:56:18.117175Z","steps":["trace[1683019590] 'agreement among raft nodes before linearized reading' (duration: 353.391913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.117283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:17.763255Z","time spent":"354.012345ms","remote":"127.0.0.1:43314","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-28T13:56:18.498845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.347753ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-28T13:56:18.498954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:18.117804Z","time spent":"381.146013ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T13:56:18.50462Z","caller":"traceutil/trace.go:171","msg":"trace[11939374] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"383.618265ms","start":"2026-04-28T13:56:18.120986Z","end":"2026-04-28T13:56:18.504605Z","steps":["trace[11939374] 'process raft request' (duration: 383.467622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.504712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:18.120973Z","time spent":"383.684692ms","remote":"127.0.0.1:43314","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-28T13:56:18.504925Z","caller":"traceutil/trace.go:171","msg":"trace[735353630] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"383.829986ms","start":"2026-04-28T13:56:18.12107Z","end":"2026-04-28T13:56:18.5049Z","steps":["trace[735353630] 'process raft request' (duration: 383.49847ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:18.504976Z","caller":"traceutil/trace.go:171","msg":"trace[1898550747] linearizableReadLoop","detail":"{readStateIndex:5062; appliedIndex:5058; }","duration":"383.612435ms","start":"2026-04-28T13:56:18.121337Z","end":"2026-04-28T13:56:18.50495Z","steps":["trace[1898550747] 'read index received' (duration: 268.165318ms)","trace[1898550747] 'applied index is now lower than readState.Index' (duration: 115.445957ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:18.505071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:18.121064Z","time spent":"383.932942ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:18.505096Z","caller":"traceutil/trace.go:171","msg":"trace[962326032] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"383.97298ms","start":"2026-04-28T13:56:18.121112Z","end":"2026-04-28T13:56:18.505085Z","steps":["trace[962326032] 'process raft request' (duration: 383.700501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.505146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:18.12109Z","time spent":"384.031717ms","remote":"127.0.0.1:43314","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-28T13:56:18.505306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.95427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-28T13:56:18.505368Z","caller":"traceutil/trace.go:171","msg":"trace[1300858356] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4880; }","duration":"384.048886ms","start":"2026-04-28T13:56:18.121304Z","end":"2026-04-28T13:56:18.505352Z","steps":["trace[1300858356] 'agreement among raft nodes before linearized reading' (duration: 383.787637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:18.50542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:18.121297Z","time spent":"384.109874ms","remote":"127.0.0.1:43230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-28T13:56:19.281377Z","caller":"traceutil/trace.go:171","msg":"trace[605339406] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"111.829954ms","start":"2026-04-28T13:56:19.169525Z","end":"2026-04-28T13:56:19.281355Z","steps":["trace[605339406] 'process raft request' (duration: 111.712629ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:19.28137Z","caller":"traceutil/trace.go:171","msg":"trace[520643149] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"111.824324ms","start":"2026-04-28T13:56:19.169514Z","end":"2026-04-28T13:56:19.281338Z","steps":["trace[520643149] 'process raft request' (duration: 111.599354ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:19.664565Z","caller":"traceutil/trace.go:171","msg":"trace[7833522] linearizableReadLoop","detail":"{readStateIndex:5077; appliedIndex:5076; }","duration":"194.506974ms","start":"2026-04-28T13:56:19.470021Z","end":"2026-04-28T13:56:19.664528Z","steps":["trace[7833522] 'read index received' (duration: 193.002949ms)","trace[7833522] 'applied index is now lower than readState.Index' (duration: 1.502365ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:19.66463Z","caller":"traceutil/trace.go:171","msg":"trace[1964670352] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"194.983784ms","start":"2026-04-28T13:56:19.469603Z","end":"2026-04-28T13:56:19.664587Z","steps":["trace[1964670352] 'process raft request' (duration: 193.381903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:19.665389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.351248ms","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-28T13:56:19.665474Z","caller":"traceutil/trace.go:171","msg":"trace[859973667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4895; }","duration":"195.520371ms","start":"2026-04-28T13:56:19.469938Z","end":"2026-04-28T13:56:19.665458Z","steps":["trace[859973667] 'agreement among raft nodes before linearized reading' (duration: 194.707476ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:24.672687Z","caller":"traceutil/trace.go:171","msg":"trace[782788464] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"132.34228ms","start":"2026-04-28T13:56:24.54032Z","end":"2026-04-28T13:56:24.672662Z","steps":["trace[782788464] 'process raft request' (duration: 38.866635ms)","trace[782788464] 'compare' (duration: 93.372639ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:24.681144Z","caller":"traceutil/trace.go:171","msg":"trace[535407644] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"136.738602ms","start":"2026-04-28T13:56:24.544382Z","end":"2026-04-28T13:56:24.681121Z","steps":["trace[535407644] 'process raft request' (duration: 136.700024ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:24.681196Z","caller":"traceutil/trace.go:171","msg":"trace[2051109735] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"140.721761ms","start":"2026-04-28T13:56:24.540447Z","end":"2026-04-28T13:56:24.681169Z","steps":["trace[2051109735] 'process raft request' (duration: 140.525568ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:26.303563Z","caller":"traceutil/trace.go:171","msg":"trace[936325327] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"100.660501ms","start":"2026-04-28T13:56:26.20288Z","end":"2026-04-28T13:56:26.30354Z","steps":["trace[936325327] 'process raft request' (duration: 100.537586ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:26.604942Z","caller":"traceutil/trace.go:171","msg":"trace[1963958063] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"128.372105ms","start":"2026-04-28T13:56:26.476545Z","end":"2026-04-28T13:56:26.604918Z","steps":["trace[1963958063] 'process raft request' (duration: 124.593754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:27.02655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.642978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:27.026604Z","caller":"traceutil/trace.go:171","msg":"trace[1718601195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4959; }","duration":"153.734973ms","start":"2026-04-28T13:56:26.872856Z","end":"2026-04-28T13:56:27.026591Z","steps":["trace[1718601195] 'range keys from in-memory index tree' (duration: 153.562031ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:27.031108Z","caller":"traceutil/trace.go:171","msg":"trace[1893624159] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"107.184616ms","start":"2026-04-28T13:56:26.923884Z","end":"2026-04-28T13:56:27.031069Z","steps":["trace[1893624159] 'process raft request' (duration: 106.978494ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:27.420565Z","caller":"traceutil/trace.go:171","msg":"trace[18619488] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"242.310766ms","start":"2026-04-28T13:56:27.178221Z","end":"2026-04-28T13:56:27.420532Z","steps":["trace[18619488] 'process raft request' (duration: 172.251662ms)","trace[18619488] 'compare' (duration: 69.915669ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:28.413559Z","caller":"traceutil/trace.go:171","msg":"trace[423152317] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"154.69336ms","start":"2026-04-28T13:56:28.258845Z","end":"2026-04-28T13:56:28.413538Z","steps":["trace[423152317] 'process raft request' (duration: 154.574844ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:56.120516Z","caller":"traceutil/trace.go:171","msg":"trace[865309055] linearizableReadLoop","detail":"{readStateIndex:5467; appliedIndex:5466; }","duration":"245.393955ms","start":"2026-04-28T13:56:55.875093Z","end":"2026-04-28T13:56:56.120487Z","steps":["trace[865309055] 'read index received' (duration: 245.162566ms)","trace[865309055] 'applied index is now lower than readState.Index' (duration: 229.899µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:56.120565Z","caller":"traceutil/trace.go:171","msg":"trace[218616428] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"246.039484ms","start":"2026-04-28T13:56:55.874491Z","end":"2026-04-28T13:56:56.120531Z","steps":["trace[218616428] 'process raft request' (duration: 245.796293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:56.120691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.580736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:56:56.120925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.516581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T13:56:56.120978Z","caller":"traceutil/trace.go:171","msg":"trace[201331466] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5274; }","duration":"116.647711ms","start":"2026-04-28T13:56:56.004314Z","end":"2026-04-28T13:56:56.120962Z","steps":["trace[201331466] 'agreement among raft nodes before linearized reading' (duration: 116.481475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:56.120926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.584478ms","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-28T13:56:56.121113Z","caller":"traceutil/trace.go:171","msg":"trace[1584242185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5274; }","duration":"183.82719ms","start":"2026-04-28T13:56:55.937266Z","end":"2026-04-28T13:56:56.121093Z","steps":["trace[1584242185] 'agreement among raft nodes before linearized reading' (duration: 183.526036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:56.121246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.796386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:56.121911Z","caller":"traceutil/trace.go:171","msg":"trace[671120439] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5274; }","duration":"155.180389ms","start":"2026-04-28T13:56:55.96632Z","end":"2026-04-28T13:56:56.121501Z","steps":["trace[671120439] 'agreement among raft nodes before linearized reading' (duration: 154.730983ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:56.12198Z","caller":"traceutil/trace.go:171","msg":"trace[1365009896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5274; }","duration":"245.664024ms","start":"2026-04-28T13:56:55.875064Z","end":"2026-04-28T13:56:56.120728Z","steps":["trace[1365009896] 'agreement among raft nodes before linearized reading' (duration: 245.572384ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:57.084351Z","caller":"traceutil/trace.go:171","msg":"trace[1776960742] linearizableReadLoop","detail":"{readStateIndex:5476; appliedIndex:5475; }","duration":"365.800716ms","start":"2026-04-28T13:56:56.71853Z","end":"2026-04-28T13:56:57.08433Z","steps":["trace[1776960742] 'read index received' (duration: 365.641571ms)","trace[1776960742] 'applied index is now lower than readState.Index' (duration: 158.555µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:57.084486Z","caller":"traceutil/trace.go:171","msg":"trace[720317573] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"425.741775ms","start":"2026-04-28T13:56:56.658646Z","end":"2026-04-28T13:56:57.084388Z","steps":["trace[720317573] 'process raft request' (duration: 425.566678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:57.084609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.558312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:57.084911Z","caller":"traceutil/trace.go:171","msg":"trace[33807211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5283; }","duration":"208.885041ms","start":"2026-04-28T13:56:56.876003Z","end":"2026-04-28T13:56:57.084888Z","steps":["trace[33807211] 'agreement among raft nodes before linearized reading' (duration: 208.577175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:57.084665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.289974ms","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-28T13:56:57.085082Z","caller":"traceutil/trace.go:171","msg":"trace[1822310183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5283; }","duration":"267.730157ms","start":"2026-04-28T13:56:56.817307Z","end":"2026-04-28T13:56:57.085037Z","steps":["trace[1822310183] 'agreement among raft nodes before linearized reading' (duration: 267.189302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:57.084538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.993106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:57.085293Z","caller":"traceutil/trace.go:171","msg":"trace[1736226214] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5283; }","duration":"366.760549ms","start":"2026-04-28T13:56:56.718512Z","end":"2026-04-28T13:56:57.085272Z","steps":["trace[1736226214] 'agreement among raft nodes before linearized reading' (duration: 365.957879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:57.084699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.977208ms","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-28T13:56:57.085399Z","caller":"traceutil/trace.go:171","msg":"trace[216707159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5283; }","duration":"172.688528ms","start":"2026-04-28T13:56:56.912694Z","end":"2026-04-28T13:56:57.085382Z","steps":["trace[216707159] 'agreement among raft nodes before linearized reading' (duration: 171.830386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:57.084721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.96032ms","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-28T13:56:57.085493Z","caller":"traceutil/trace.go:171","msg":"trace[550417661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5283; }","duration":"179.75657ms","start":"2026-04-28T13:56:56.905723Z","end":"2026-04-28T13:56:57.08548Z","steps":["trace[550417661] 'agreement among raft nodes before linearized reading' (duration: 178.877224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:57.084874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:56.658624Z","time spent":"426.171538ms","remote":"127.0.0.1:43230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7776,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:57.085355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:56.718449Z","time spent":"366.889417ms","remote":"127.0.0.1:43470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:57.402669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.41329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:57.403286Z","caller":"traceutil/trace.go:171","msg":"trace[2120633972] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"312.565894ms","start":"2026-04-28T13:56:57.090685Z","end":"2026-04-28T13:56:57.403251Z","steps":["trace[2120633972] 'process raft request' (duration: 312.175492ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:57.403356Z","caller":"traceutil/trace.go:171","msg":"trace[946706839] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"312.792443ms","start":"2026-04-28T13:56:57.090537Z","end":"2026-04-28T13:56:57.403329Z","steps":["trace[946706839] 'process raft request' (duration: 51.632525ms)","trace[946706839] 'compare' (duration: 260.224479ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:57.403489Z","caller":"traceutil/trace.go:171","msg":"trace[1567161510] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"158.364439ms","start":"2026-04-28T13:56:57.245002Z","end":"2026-04-28T13:56:57.403366Z","steps":["trace[1567161510] 'process raft request' (duration: 157.949552ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:57.403518Z","caller":"traceutil/trace.go:171","msg":"trace[1885194814] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"312.87424ms","start":"2026-04-28T13:56:57.090607Z","end":"2026-04-28T13:56:57.403482Z","steps":["trace[1885194814] 'process raft request' (duration: 312.164939ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:57.403524Z","caller":"traceutil/trace.go:171","msg":"trace[1416975706] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"301.985297ms","start":"2026-04-28T13:56:57.101487Z","end":"2026-04-28T13:56:57.403472Z","steps":["trace[1416975706] 'process raft request' (duration: 301.407675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:57.403679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:57.101467Z","time spent":"302.133759ms","remote":"127.0.0.1:43230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12114,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:57.403514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:57.090522Z","time spent":"312.903116ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:57.403667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:57.090583Z","time spent":"313.008088ms","remote":"127.0.0.1:43314","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-28T13:56:57.403651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:57.09068Z","time spent":"312.736391ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:57:24.852392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2857} {"level":"info","ts":"2026-04-28T13:57:24.93256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2857,"took":"77.394386ms","hash":3170121933,"current-db-size-bytes":52826112,"current-db-size":"53 MB","current-db-size-in-use-bytes":28545024,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-28T13:57:24.93264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3170121933,"revision":2857,"compact-revision":-1} {"level":"info","ts":"2026-04-28T13:57:32.41489Z","caller":"traceutil/trace.go:171","msg":"trace[1472368868] linearizableReadLoop","detail":"{readStateIndex:5706; appliedIndex:5704; }","duration":"102.755997ms","start":"2026-04-28T13:57:32.312104Z","end":"2026-04-28T13:57:32.41486Z","steps":["trace[1472368868] 'read index received' (duration: 43.460389ms)","trace[1472368868] 'applied index is now lower than readState.Index' (duration: 59.294828ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:57:32.415198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.076509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T13:57:32.415276Z","caller":"traceutil/trace.go:171","msg":"trace[349685190] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5504; }","duration":"103.154493ms","start":"2026-04-28T13:57:32.312068Z","end":"2026-04-28T13:57:32.415222Z","steps":["trace[349685190] 'agreement among raft nodes before linearized reading' (duration: 102.89786ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:32.415465Z","caller":"traceutil/trace.go:171","msg":"trace[614549723] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"105.633973ms","start":"2026-04-28T13:57:32.309792Z","end":"2026-04-28T13:57:32.415426Z","steps":["trace[614549723] 'process raft request' (duration: 99.607156ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:36.103576Z","caller":"traceutil/trace.go:171","msg":"trace[1296143725] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"166.399179ms","start":"2026-04-28T13:57:35.937149Z","end":"2026-04-28T13:57:36.103548Z","steps":["trace[1296143725] 'process raft request' (duration: 166.163731ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:36.122478Z","caller":"traceutil/trace.go:171","msg":"trace[826708253] transaction","detail":"{read_only:false; response_revision:5528; number_of_response:1; }","duration":"149.192331ms","start":"2026-04-28T13:57:35.973258Z","end":"2026-04-28T13:57:36.12245Z","steps":["trace[826708253] 'process raft request' (duration: 149.038636ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:58:16.852798Z","caller":"traceutil/trace.go:171","msg":"trace[1738395842] transaction","detail":"{read_only:false; response_revision:5843; number_of_response:1; }","duration":"142.893399ms","start":"2026-04-28T13:58:16.709872Z","end":"2026-04-28T13:58:16.852766Z","steps":["trace[1738395842] 'process raft request' (duration: 113.698756ms)","trace[1738395842] 'compare' (duration: 29.075289ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:02:24.858229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5463} {"level":"info","ts":"2026-04-28T14:02:24.937421Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5463,"took":"77.642411ms","hash":501282213,"current-db-size-bytes":52826112,"current-db-size":"53 MB","current-db-size-in-use-bytes":18386944,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-28T14:02:24.937555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":501282213,"revision":5463,"compact-revision":2857}