{"level":"warn","ts":"2026-04-15T19:31:24.927225Z","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-15T19:31:24.927326Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-15T19:31:24.927441Z","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-15T19:31:24.927487Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-04-15T19:31:24.927527Z","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-15T19:31:24.928409Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-04-15T19:31:24.928551Z","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.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-15T19:31:24.935012Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.153138ms"} {"level":"info","ts":"2026-04-15T19:31:24.943009Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-04-15T19:31:24.943122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-04-15T19:31:24.943153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-04-15T19:31:24.943167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-15T19:31:24.943201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-04-15T19:31:24.943266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-04-15T19:31:24.949055Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-15T19:31:24.950315Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-15T19:31:24.95114Z","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-15T19:31:24.953199Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-15T19:31:24.953302Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-15T19:31:24.953583Z","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-15T19:31:24.953655Z","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-15T19:31:24.953665Z","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-15T19:31:24.955247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-04-15T19:31:24.955416Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-04-15T19:31:24.958344Z","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-15T19:31:24.958464Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-04-15T19:31:24.95854Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-04-15T19:31:24.95866Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-15T19:31:24.958706Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-15T19:31:25.044406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-04-15T19:31:25.044447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-15T19:31:25.044474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-04-15T19:31:25.044487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-04-15T19:31:25.044494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-04-15T19:31:25.044505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-04-15T19:31:25.044513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-04-15T19:31:25.045468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T19:31:25.04629Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T19:31:25.046547Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T19:31:25.046562Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T19:31:25.04666Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T19:31:25.046714Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T19:31:25.046839Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-15T19:31:25.046854Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-15T19:31:25.048368Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-15T19:31:25.046303Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-04-15T19:31:25.049896Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-04-15T19:31:27.536809Z","caller":"traceutil/trace.go:171","msg":"trace[1376530153] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"185.026785ms","start":"2026-04-15T19:31:27.351734Z","end":"2026-04-15T19:31:27.536761Z","steps":["trace[1376530153] 'read index received' (duration: 184.865911ms)","trace[1376530153] 'applied index is now lower than readState.Index' (duration: 160.044µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:31:27.536876Z","caller":"traceutil/trace.go:171","msg":"trace[716908981] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"186.154619ms","start":"2026-04-15T19:31:27.350689Z","end":"2026-04-15T19:31:27.536843Z","steps":["trace[716908981] 'process raft request' (duration: 185.956385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:31:27.537083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.303441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-15T19:31:27.537181Z","caller":"traceutil/trace.go:171","msg":"trace[1551414173] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"185.435834ms","start":"2026-04-15T19:31:27.351721Z","end":"2026-04-15T19:31:27.537157Z","steps":["trace[1551414173] 'agreement among raft nodes before linearized reading' (duration: 185.189089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:31:28.203419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.709972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-15T19:31:28.203528Z","caller":"traceutil/trace.go:171","msg":"trace[1198390488] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"660.90881ms","start":"2026-04-15T19:31:27.542591Z","end":"2026-04-15T19:31:28.2035Z","steps":["trace[1198390488] 'read index received' (duration: 459.541964ms)","trace[1198390488] 'applied index is now lower than readState.Index' (duration: 201.365656ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:31:28.203537Z","caller":"traceutil/trace.go:171","msg":"trace[31527424] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"661.462441ms","start":"2026-04-15T19:31:27.542037Z","end":"2026-04-15T19:31:28.2035Z","steps":["trace[31527424] 'process raft request' (duration: 460.143166ms)","trace[31527424] 'compare' (duration: 200.58458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:31:28.203663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.069443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-15T19:31:28.203695Z","caller":"traceutil/trace.go:171","msg":"trace[1805634199] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"661.116694ms","start":"2026-04-15T19:31:27.54257Z","end":"2026-04-15T19:31:28.203687Z","steps":["trace[1805634199] 'agreement among raft nodes before linearized reading' (duration: 660.986081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:31:28.203724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:31:27.542562Z","time spent":"661.155304ms","remote":"127.0.0.1:41958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-04-15T19:31:28.203692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:31:27.542019Z","time spent":"661.546643ms","remote":"127.0.0.1:41978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T19:31:53.583249Z","caller":"traceutil/trace.go:171","msg":"trace[81259690] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"138.064101ms","start":"2026-04-15T19:31:53.44516Z","end":"2026-04-15T19:31:53.583224Z","steps":["trace[81259690] 'process raft request' (duration: 137.894629ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.589348Z","caller":"traceutil/trace.go:171","msg":"trace[295383128] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"119.215154ms","start":"2026-04-15T19:31:53.470118Z","end":"2026-04-15T19:31:53.589333Z","steps":["trace[295383128] 'process raft request' (duration: 118.92217ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.589393Z","caller":"traceutil/trace.go:171","msg":"trace[531563765] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"119.183284ms","start":"2026-04-15T19:31:53.470178Z","end":"2026-04-15T19:31:53.589362Z","steps":["trace[531563765] 'process raft request' (duration: 118.9028ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.589517Z","caller":"traceutil/trace.go:171","msg":"trace[1527491576] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"139.616362ms","start":"2026-04-15T19:31:53.44989Z","end":"2026-04-15T19:31:53.589506Z","steps":["trace[1527491576] 'process raft request' (duration: 138.76001ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.589561Z","caller":"traceutil/trace.go:171","msg":"trace[1348477354] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"128.091245ms","start":"2026-04-15T19:31:53.46144Z","end":"2026-04-15T19:31:53.589531Z","steps":["trace[1348477354] 'process raft request' (duration: 127.429646ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.589475Z","caller":"traceutil/trace.go:171","msg":"trace[1409808733] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"127.788981ms","start":"2026-04-15T19:31:53.461674Z","end":"2026-04-15T19:31:53.589463Z","steps":["trace[1409808733] 'process raft request' (duration: 127.278814ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.589679Z","caller":"traceutil/trace.go:171","msg":"trace[108637796] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"137.99411ms","start":"2026-04-15T19:31:53.451677Z","end":"2026-04-15T19:31:53.589671Z","steps":["trace[108637796] 'process raft request' (duration: 137.137688ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.589335Z","caller":"traceutil/trace.go:171","msg":"trace[20401063] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"127.812681ms","start":"2026-04-15T19:31:53.461499Z","end":"2026-04-15T19:31:53.589312Z","steps":["trace[20401063] 'process raft request' (duration: 127.413416ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.796204Z","caller":"traceutil/trace.go:171","msg":"trace[1819381055] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"205.676382ms","start":"2026-04-15T19:31:53.590495Z","end":"2026-04-15T19:31:53.796172Z","steps":["trace[1819381055] 'process raft request' (duration: 205.195865ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.796274Z","caller":"traceutil/trace.go:171","msg":"trace[144058261] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"202.121184ms","start":"2026-04-15T19:31:53.594122Z","end":"2026-04-15T19:31:53.796244Z","steps":["trace[144058261] 'process raft request' (duration: 201.972242ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.79641Z","caller":"traceutil/trace.go:171","msg":"trace[1444448330] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"187.017618ms","start":"2026-04-15T19:31:53.609363Z","end":"2026-04-15T19:31:53.796381Z","steps":["trace[1444448330] 'process raft request' (duration: 186.948147ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.796382Z","caller":"traceutil/trace.go:171","msg":"trace[1646290143] transaction","detail":"{read_only:false; number_of_response:1; response_revision:478; }","duration":"197.889175ms","start":"2026-04-15T19:31:53.598474Z","end":"2026-04-15T19:31:53.796363Z","steps":["trace[1646290143] 'process raft request' (duration: 197.706883ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.796469Z","caller":"traceutil/trace.go:171","msg":"trace[1380745635] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"199.326865ms","start":"2026-04-15T19:31:53.597112Z","end":"2026-04-15T19:31:53.796438Z","steps":["trace[1380745635] 'process raft request' (duration: 199.034321ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.796637Z","caller":"traceutil/trace.go:171","msg":"trace[1129965696] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"187.348992ms","start":"2026-04-15T19:31:53.609272Z","end":"2026-04-15T19:31:53.796621Z","steps":["trace[1129965696] 'process raft request' (duration: 187.009617ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.796655Z","caller":"traceutil/trace.go:171","msg":"trace[1846589788] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"197.882605ms","start":"2026-04-15T19:31:53.598718Z","end":"2026-04-15T19:31:53.796601Z","steps":["trace[1846589788] 'process raft request' (duration: 197.49961ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.797089Z","caller":"traceutil/trace.go:171","msg":"trace[211338669] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"187.671166ms","start":"2026-04-15T19:31:53.609402Z","end":"2026-04-15T19:31:53.797074Z","steps":["trace[211338669] 'process raft request' (duration: 187.591325ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.797215Z","caller":"traceutil/trace.go:171","msg":"trace[1185087851] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"187.744917ms","start":"2026-04-15T19:31:53.609367Z","end":"2026-04-15T19:31:53.797112Z","steps":["trace[1185087851] 'process raft request' (duration: 186.997347ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.806772Z","caller":"traceutil/trace.go:171","msg":"trace[1608494391] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"192.451432ms","start":"2026-04-15T19:31:53.614297Z","end":"2026-04-15T19:31:53.806748Z","steps":["trace[1608494391] 'process raft request' (duration: 191.813633ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.806928Z","caller":"traceutil/trace.go:171","msg":"trace[1198294949] transaction","detail":"{read_only:false; number_of_response:0; response_revision:484; }","duration":"191.329626ms","start":"2026-04-15T19:31:53.61559Z","end":"2026-04-15T19:31:53.80692Z","steps":["trace[1198294949] 'process raft request' (duration: 190.666837ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:53.807035Z","caller":"traceutil/trace.go:171","msg":"trace[2017090527] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"175.918747ms","start":"2026-04-15T19:31:53.631106Z","end":"2026-04-15T19:31:53.807025Z","steps":["trace[2017090527] 'process raft request' (duration: 175.201557ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:31:59.132549Z","caller":"traceutil/trace.go:171","msg":"trace[138793090] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"115.69774ms","start":"2026-04-15T19:31:59.016809Z","end":"2026-04-15T19:31:59.132507Z","steps":["trace[138793090] 'process raft request' (duration: 115.215564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:00.293391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153698601678836694,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T19:32:00.637076Z","caller":"traceutil/trace.go:171","msg":"trace[691506309] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"906.216978ms","start":"2026-04-15T19:31:59.730833Z","end":"2026-04-15T19:32:00.63705Z","steps":["trace[691506309] 'process raft request' (duration: 906.046186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:00.637783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:31:59.730812Z","time spent":"906.39868ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:32:01.344114Z","caller":"traceutil/trace.go:171","msg":"trace[2040243825] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"1.550987469s","start":"2026-04-15T19:31:59.793101Z","end":"2026-04-15T19:32:01.344088Z","steps":["trace[2040243825] 'read index received' (duration: 843.903603ms)","trace[2040243825] 'applied index is now lower than readState.Index' (duration: 707.082776ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:32:01.344185Z","caller":"traceutil/trace.go:171","msg":"trace[1473157805] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.288686567s","start":"2026-04-15T19:32:00.05548Z","end":"2026-04-15T19:32:01.344167Z","steps":["trace[1473157805] 'process raft request' (duration: 1.288061819s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:01.344248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.55113504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:32:01.344331Z","caller":"traceutil/trace.go:171","msg":"trace[1974358247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"1.551290172s","start":"2026-04-15T19:31:59.793017Z","end":"2026-04-15T19:32:01.344307Z","steps":["trace[1974358247] 'agreement among raft nodes before linearized reading' (duration: 1.551168121s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:01.344376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:31:59.792981Z","time spent":"1.551387943s","remote":"127.0.0.1:41650","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-15T19:32:01.344504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:32:00.055449Z","time spent":"1.288784988s","remote":"127.0.0.1:41734","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-15T19:32:01.395144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317881233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-15T19:32:01.395163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.328093ms","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-15T19:32:01.395198Z","caller":"traceutil/trace.go:171","msg":"trace[644267242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"1.318013734s","start":"2026-04-15T19:32:00.077171Z","end":"2026-04-15T19:32:01.395185Z","steps":["trace[644267242] 'agreement among raft nodes before linearized reading' (duration: 1.317839322s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:01.395224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:32:00.077152Z","time spent":"1.318067815s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T19:32:01.395241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42400494s","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-15T19:32:01.395258Z","caller":"traceutil/trace.go:171","msg":"trace[1428868718] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"150.22999ms","start":"2026-04-15T19:32:01.245017Z","end":"2026-04-15T19:32:01.395247Z","steps":["trace[1428868718] 'process raft request' (duration: 149.834335ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:32:01.395298Z","caller":"traceutil/trace.go:171","msg":"trace[376208436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"1.424097042s","start":"2026-04-15T19:31:59.971186Z","end":"2026-04-15T19:32:01.395283Z","steps":["trace[376208436] 'agreement among raft nodes before linearized reading' (duration: 1.42395785s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:01.395336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:31:59.971166Z","time spent":"1.424163512s","remote":"127.0.0.1:41878","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-15T19:32:01.395389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.825088ms","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-15T19:32:01.395408Z","caller":"traceutil/trace.go:171","msg":"trace[1472447766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"602.873638ms","start":"2026-04-15T19:32:00.79253Z","end":"2026-04-15T19:32:01.395404Z","steps":["trace[1472447766] 'agreement among raft nodes before linearized reading' (duration: 602.826518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:01.395422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:32:00.792508Z","time spent":"602.911448ms","remote":"127.0.0.1:41878","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-15T19:32:01.395218Z","caller":"traceutil/trace.go:171","msg":"trace[936817277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"241.424404ms","start":"2026-04-15T19:32:01.153778Z","end":"2026-04-15T19:32:01.395203Z","steps":["trace[936817277] 'agreement among raft nodes before linearized reading' (duration: 241.262812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:02.80186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.912828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T19:32:02.802172Z","caller":"traceutil/trace.go:171","msg":"trace[1685067155] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.400949051s","start":"2026-04-15T19:32:01.401179Z","end":"2026-04-15T19:32:02.802128Z","steps":["trace[1685067155] 'process raft request' (duration: 409.632998ms)","trace[1685067155] 'compare' (duration: 989.790695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:32:02.802345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:32:01.401159Z","time spent":"1.401099943s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:32:02.80235Z","caller":"traceutil/trace.go:171","msg":"trace[1871976756] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.400353124s","start":"2026-04-15T19:32:01.401963Z","end":"2026-04-15T19:32:02.802316Z","steps":["trace[1871976756] 'process raft request' (duration: 1.4000773s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:02.80248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:32:01.401956Z","time spent":"1.400453515s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:32:02.802507Z","caller":"traceutil/trace.go:171","msg":"trace[573894993] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.398129667s","start":"2026-04-15T19:32:01.404363Z","end":"2026-04-15T19:32:02.802492Z","steps":["trace[573894993] 'process raft request' (duration: 1.397779933s)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:32:02.802534Z","caller":"traceutil/trace.go:171","msg":"trace[897663706] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.400919461s","start":"2026-04-15T19:32:01.40159Z","end":"2026-04-15T19:32:02.802509Z","steps":["trace[897663706] 'process raft request' (duration: 1.400359064s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:02.802598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:32:01.404355Z","time spent":"1.398211568s","remote":"127.0.0.1:41864","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-15T19:32:02.802642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:32:01.401582Z","time spent":"1.401002102s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:32:02.802664Z","caller":"traceutil/trace.go:171","msg":"trace[1863852980] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.353856951s","start":"2026-04-15T19:32:01.448796Z","end":"2026-04-15T19:32:02.802653Z","steps":["trace[1863852980] 'process raft request' (duration: 1.353383155s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:02.802716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:32:01.448767Z","time spent":"1.353922481s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:32:24.78679Z","caller":"traceutil/trace.go:171","msg":"trace[650913824] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"395.636525ms","start":"2026-04-15T19:32:24.391123Z","end":"2026-04-15T19:32:24.78676Z","steps":["trace[650913824] 'process raft request' (duration: 395.441753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:24.786974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:32:24.391103Z","time spent":"395.791306ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:32:53.106247Z","caller":"traceutil/trace.go:171","msg":"trace[82761147] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"179.947473ms","start":"2026-04-15T19:32:52.926269Z","end":"2026-04-15T19:32:53.106217Z","steps":["trace[82761147] 'process raft request' (duration: 179.769956ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:32:53.243607Z","caller":"traceutil/trace.go:171","msg":"trace[2122763242] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1192; }","duration":"175.844651ms","start":"2026-04-15T19:32:53.067734Z","end":"2026-04-15T19:32:53.243579Z","steps":["trace[2122763242] 'read index received' (duration: 38.331507ms)","trace[2122763242] 'applied index is now lower than readState.Index' (duration: 137.511594ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:32:53.243722Z","caller":"traceutil/trace.go:171","msg":"trace[2051412986] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"230.12958ms","start":"2026-04-15T19:32:53.013537Z","end":"2026-04-15T19:32:53.243667Z","steps":["trace[2051412986] 'process raft request' (duration: 229.846669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:53.243989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.225617ms","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-15T19:32:53.243977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.773929ms","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-15T19:32:53.244045Z","caller":"traceutil/trace.go:171","msg":"trace[1351425460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1152; }","duration":"176.342271ms","start":"2026-04-15T19:32:53.067688Z","end":"2026-04-15T19:32:53.24403Z","steps":["trace[1351425460] 'agreement among raft nodes before linearized reading' (duration: 176.047739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:32:53.244032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.222366ms","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-15T19:32:53.24413Z","caller":"traceutil/trace.go:171","msg":"trace[17320552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1152; }","duration":"170.352301ms","start":"2026-04-15T19:32:53.073749Z","end":"2026-04-15T19:32:53.244101Z","steps":["trace[17320552] 'agreement among raft nodes before linearized reading' (duration: 170.037579ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:32:53.244059Z","caller":"traceutil/trace.go:171","msg":"trace[1685025488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1152; }","duration":"169.898634ms","start":"2026-04-15T19:32:53.074143Z","end":"2026-04-15T19:32:53.244042Z","steps":["trace[1685025488] 'agreement among raft nodes before linearized reading' (duration: 169.669735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:10.17097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.825213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-kshzv\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-04-15T19:33:10.171065Z","caller":"traceutil/trace.go:171","msg":"trace[1829183412] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-kshzv; range_end:; response_count:1; response_revision:1296; }","duration":"133.953048ms","start":"2026-04-15T19:33:10.037093Z","end":"2026-04-15T19:33:10.171046Z","steps":["trace[1829183412] 'range keys from in-memory index tree' (duration: 133.662908ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:16.032151Z","caller":"traceutil/trace.go:171","msg":"trace[1099667732] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"283.206538ms","start":"2026-04-15T19:33:15.748919Z","end":"2026-04-15T19:33:16.032125Z","steps":["trace[1099667732] 'process raft request' (duration: 283.055244ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:18.987169Z","caller":"traceutil/trace.go:171","msg":"trace[873900469] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"105.639006ms","start":"2026-04-15T19:33:18.881507Z","end":"2026-04-15T19:33:18.987146Z","steps":["trace[873900469] 'process raft request' (duration: 105.463591ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:36.806658Z","caller":"traceutil/trace.go:171","msg":"trace[660536414] linearizableReadLoop","detail":"{readStateIndex:1815; appliedIndex:1814; }","duration":"171.559253ms","start":"2026-04-15T19:33:36.635081Z","end":"2026-04-15T19:33:36.80664Z","steps":["trace[660536414] 'read index received' (duration: 171.385389ms)","trace[660536414] 'applied index is now lower than readState.Index' (duration: 173.284µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:33:36.806758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.667905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:33:36.806728Z","caller":"traceutil/trace.go:171","msg":"trace[842207199] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"276.580772ms","start":"2026-04-15T19:33:36.530099Z","end":"2026-04-15T19:33:36.80668Z","steps":["trace[842207199] 'process raft request' (duration: 276.437319ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:36.806777Z","caller":"traceutil/trace.go:171","msg":"trace[815137601] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1736; }","duration":"171.743897ms","start":"2026-04-15T19:33:36.635028Z","end":"2026-04-15T19:33:36.806772Z","steps":["trace[815137601] 'agreement among raft nodes before linearized reading' (duration: 171.690086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:44.977179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.460886ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-15T19:33:44.977742Z","caller":"traceutil/trace.go:171","msg":"trace[1799050868] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"107.232843ms","start":"2026-04-15T19:33:44.870487Z","end":"2026-04-15T19:33:44.97772Z","steps":["trace[1799050868] 'process raft request' (duration: 107.195612ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:44.977849Z","caller":"traceutil/trace.go:171","msg":"trace[757492214] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"822.151475ms","start":"2026-04-15T19:33:44.15566Z","end":"2026-04-15T19:33:44.977811Z","steps":["trace[757492214] 'read index received' (duration: 48.997193ms)","trace[757492214] 'applied index is now lower than readState.Index' (duration: 773.151222ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:44.977923Z","caller":"traceutil/trace.go:171","msg":"trace[1579317680] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"387.442336ms","start":"2026-04-15T19:33:44.590366Z","end":"2026-04-15T19:33:44.977808Z","steps":["trace[1579317680] 'process raft request' (duration: 387.219481ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:44.97781Z","caller":"traceutil/trace.go:171","msg":"trace[494316336] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"687.477996ms","start":"2026-04-15T19:33:44.290303Z","end":"2026-04-15T19:33:44.977781Z","steps":["trace[494316336] 'process raft request' (duration: 687.121668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:44.978114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:44.590347Z","time spent":"387.662082ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:44.978231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.548194ms","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-15T19:33:44.978279Z","caller":"traceutil/trace.go:171","msg":"trace[1728000311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1851; }","duration":"822.651917ms","start":"2026-04-15T19:33:44.155614Z","end":"2026-04-15T19:33:44.978266Z","steps":["trace[1728000311] 'agreement among raft nodes before linearized reading' (duration: 822.37708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:44.978322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:44.155593Z","time spent":"822.720509ms","remote":"127.0.0.1:41878","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-15T19:33:44.978333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.794356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b665e636-d7f1-456f-a04b-54b4e60959c9\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-15T19:33:44.978371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.071378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b665e636-d7f1-456f-a04b-54b4e60959c9\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-15T19:33:44.978413Z","caller":"traceutil/trace.go:171","msg":"trace[968833502] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b665e636-d7f1-456f-a04b-54b4e60959c9; range_end:; response_count:1; response_revision:1851; }","duration":"421.14599ms","start":"2026-04-15T19:33:44.557253Z","end":"2026-04-15T19:33:44.978399Z","steps":["trace[968833502] 'agreement among raft nodes before linearized reading' (duration: 421.018837ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:44.978413Z","caller":"traceutil/trace.go:171","msg":"trace[1884167561] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b665e636-d7f1-456f-a04b-54b4e60959c9; range_end:; response_count:1; response_revision:1851; }","duration":"764.914919ms","start":"2026-04-15T19:33:44.213479Z","end":"2026-04-15T19:33:44.978394Z","steps":["trace[1884167561] 'agreement among raft nodes before linearized reading' (duration: 764.688793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:44.978467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:44.21346Z","time spent":"764.99588ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b665e636-d7f1-456f-a04b-54b4e60959c9\" "} {"level":"warn","ts":"2026-04-15T19:33:44.978494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:44.290242Z","time spent":"687.869216ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:44.978516Z","caller":"traceutil/trace.go:171","msg":"trace[572610526] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"151.843812ms","start":"2026-04-15T19:33:44.826659Z","end":"2026-04-15T19:33:44.978503Z","steps":["trace[572610526] 'process raft request' (duration: 150.970142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:44.978548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.497862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T19:33:44.978466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:44.557235Z","time spent":"421.220641ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b665e636-d7f1-456f-a04b-54b4e60959c9\" "} {"level":"info","ts":"2026-04-15T19:33:44.978597Z","caller":"traceutil/trace.go:171","msg":"trace[277685644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"187.608584ms","start":"2026-04-15T19:33:44.790975Z","end":"2026-04-15T19:33:44.978584Z","steps":["trace[277685644] 'agreement among raft nodes before linearized reading' (duration: 187.535003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:44.978581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.551738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-15T19:33:44.978712Z","caller":"traceutil/trace.go:171","msg":"trace[70696185] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1851; }","duration":"721.729582ms","start":"2026-04-15T19:33:44.256943Z","end":"2026-04-15T19:33:44.978672Z","steps":["trace[70696185] 'agreement among raft nodes before linearized reading' (duration: 721.354964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:44.978712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.687303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-15T19:33:44.978847Z","caller":"traceutil/trace.go:171","msg":"trace[1940444677] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"730.870268ms","start":"2026-04-15T19:33:44.24795Z","end":"2026-04-15T19:33:44.97882Z","steps":["trace[1940444677] 'agreement among raft nodes before linearized reading' (duration: 730.377476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:44.978779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:44.256928Z","time spent":"721.841795ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-15T19:33:44.978978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:44.24794Z","time spent":"730.96377ms","remote":"127.0.0.1:49010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-15T19:33:45.576574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153698601678839253,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T19:33:45.832661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.033183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T19:33:45.832835Z","caller":"traceutil/trace.go:171","msg":"trace[2103993868] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"849.035582ms","start":"2026-04-15T19:33:44.983724Z","end":"2026-04-15T19:33:45.832759Z","steps":["trace[2103993868] 'process raft request' (duration: 626.798195ms)","trace[2103993868] 'compare' (duration: 221.756736ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:33:45.832991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:44.983685Z","time spent":"849.226297ms","remote":"127.0.0.1:41878","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-15T19:33:45.842611Z","caller":"traceutil/trace.go:171","msg":"trace[1504794648] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"857.603762ms","start":"2026-04-15T19:33:44.984989Z","end":"2026-04-15T19:33:45.842593Z","steps":["trace[1504794648] 'process raft request' (duration: 857.453249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:45.842744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:44.98498Z","time spent":"857.689024ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:33:45.842809Z","caller":"traceutil/trace.go:171","msg":"trace[978808656] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1932; }","duration":"766.455281ms","start":"2026-04-15T19:33:45.076339Z","end":"2026-04-15T19:33:45.842795Z","steps":["trace[978808656] 'read index received' (duration: 534.141208ms)","trace[978808656] 'applied index is now lower than readState.Index' (duration: 232.313223ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:33:45.842884Z","caller":"traceutil/trace.go:171","msg":"trace[125073688] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"856.294792ms","start":"2026-04-15T19:33:44.986574Z","end":"2026-04-15T19:33:45.842869Z","steps":["trace[125073688] 'process raft request' (duration: 855.973944ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:45.842901Z","caller":"traceutil/trace.go:171","msg":"trace[2070164230] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"422.725858ms","start":"2026-04-15T19:33:45.420168Z","end":"2026-04-15T19:33:45.842894Z","steps":["trace[2070164230] 'process raft request' (duration: 422.565744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:45.842951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:44.986552Z","time spent":"856.355032ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:33:45.843049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:45.420151Z","time spent":"422.771879ms","remote":"127.0.0.1:41878","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-15T19:33:45.843165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.654132ms","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-15T19:33:45.843252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.114248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-15T19:33:45.843288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.957232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-04-15T19:33:45.843189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.180841ms","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-15T19:33:45.843337Z","caller":"traceutil/trace.go:171","msg":"trace[483203531] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1855; }","duration":"767.025894ms","start":"2026-04-15T19:33:45.076292Z","end":"2026-04-15T19:33:45.843318Z","steps":["trace[483203531] 'agreement among raft nodes before linearized reading' (duration: 766.938782ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:45.843334Z","caller":"traceutil/trace.go:171","msg":"trace[360413277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1855; }","duration":"364.373785ms","start":"2026-04-15T19:33:45.47895Z","end":"2026-04-15T19:33:45.843324Z","steps":["trace[360413277] 'agreement among raft nodes before linearized reading' (duration: 364.048628ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:45.843356Z","caller":"traceutil/trace.go:171","msg":"trace[895950908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1855; }","duration":"471.199609ms","start":"2026-04-15T19:33:45.372097Z","end":"2026-04-15T19:33:45.843297Z","steps":["trace[895950908] 'agreement among raft nodes before linearized reading' (duration: 470.864442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:45.843383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:45.076277Z","time spent":"767.099075ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-15T19:33:45.843295Z","caller":"traceutil/trace.go:171","msg":"trace[92733497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1855; }","duration":"364.827116ms","start":"2026-04-15T19:33:45.478456Z","end":"2026-04-15T19:33:45.843283Z","steps":["trace[92733497] 'agreement among raft nodes before linearized reading' (duration: 364.572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:45.843401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:45.372014Z","time spent":"471.376054ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T19:33:45.843393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:45.478937Z","time spent":"364.443997ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T19:33:45.843494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:45.478446Z","time spent":"365.0034ms","remote":"127.0.0.1:41878","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-15T19:33:45.843806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.822146ms","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-15T19:33:45.843931Z","caller":"traceutil/trace.go:171","msg":"trace[1952417139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1855; }","duration":"368.059272ms","start":"2026-04-15T19:33:45.475848Z","end":"2026-04-15T19:33:45.843907Z","steps":["trace[1952417139] 'agreement among raft nodes before linearized reading' (duration: 367.435537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:33:45.844149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:45.475826Z","time spent":"368.304787ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T19:33:57.133191Z","caller":"traceutil/trace.go:171","msg":"trace[1976399963] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"109.80578ms","start":"2026-04-15T19:33:57.023363Z","end":"2026-04-15T19:33:57.133169Z","steps":["trace[1976399963] 'process raft request' (duration: 109.624606ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:57.154345Z","caller":"traceutil/trace.go:171","msg":"trace[521207532] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"127.073749ms","start":"2026-04-15T19:33:57.027249Z","end":"2026-04-15T19:33:57.154323Z","steps":["trace[521207532] 'process raft request' (duration: 126.925616ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:33:57.154602Z","caller":"traceutil/trace.go:171","msg":"trace[304757686] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"127.066688ms","start":"2026-04-15T19:33:57.027521Z","end":"2026-04-15T19:33:57.154588Z","steps":["trace[304757686] 'process raft request' (duration: 126.768452ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:00.210128Z","caller":"traceutil/trace.go:171","msg":"trace[1925895325] linearizableReadLoop","detail":"{readStateIndex:2061; appliedIndex:2060; }","duration":"308.732397ms","start":"2026-04-15T19:33:59.901376Z","end":"2026-04-15T19:34:00.210108Z","steps":["trace[1925895325] 'read index received' (duration: 308.598204ms)","trace[1925895325] 'applied index is now lower than readState.Index' (duration: 133.043µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:00.210179Z","caller":"traceutil/trace.go:171","msg":"trace[1444872906] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"320.203418ms","start":"2026-04-15T19:33:59.889955Z","end":"2026-04-15T19:34:00.210158Z","steps":["trace[1444872906] 'process raft request' (duration: 320.021874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:00.210278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:59.889937Z","time spent":"320.287719ms","remote":"127.0.0.1:41878","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-15T19:34:00.210369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.983102ms","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-15T19:34:00.210414Z","caller":"traceutil/trace.go:171","msg":"trace[1442897639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1977; }","duration":"309.038473ms","start":"2026-04-15T19:33:59.901365Z","end":"2026-04-15T19:34:00.210403Z","steps":["trace[1442897639] 'agreement among raft nodes before linearized reading' (duration: 308.90632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:00.210421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.144408ms","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-15T19:34:00.210453Z","caller":"traceutil/trace.go:171","msg":"trace[1469644508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1977; }","duration":"292.189399ms","start":"2026-04-15T19:33:59.918255Z","end":"2026-04-15T19:34:00.210444Z","steps":["trace[1469644508] 'agreement among raft nodes before linearized reading' (duration: 292.078957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:00.210449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:59.901316Z","time spent":"309.123435ms","remote":"127.0.0.1:41878","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-15T19:34:00.210482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.294292ms","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-15T19:34:00.210498Z","caller":"traceutil/trace.go:171","msg":"trace[1706887016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1977; }","duration":"293.342203ms","start":"2026-04-15T19:33:59.917151Z","end":"2026-04-15T19:34:00.210493Z","steps":["trace[1706887016] 'agreement among raft nodes before linearized reading' (duration: 293.288652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:00.210469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.777859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-15T19:34:00.210499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.551059ms","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-15T19:34:00.210609Z","caller":"traceutil/trace.go:171","msg":"trace[789320387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1977; }","duration":"285.688762ms","start":"2026-04-15T19:33:59.924903Z","end":"2026-04-15T19:34:00.210591Z","steps":["trace[789320387] 'agreement among raft nodes before linearized reading' (duration: 285.410576ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:00.210542Z","caller":"traceutil/trace.go:171","msg":"trace[199440603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1977; }","duration":"299.881531ms","start":"2026-04-15T19:33:59.910644Z","end":"2026-04-15T19:34:00.210525Z","steps":["trace[199440603] 'agreement among raft nodes before linearized reading' (duration: 299.665116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:00.210711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:33:59.910638Z","time spent":"300.061724ms","remote":"127.0.0.1:41878","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-15T19:34:01.358645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153698601678839521,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T19:34:01.859483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153698601678839521,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T19:34:01.88447Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033862632s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T19:34:01.88486Z","caller":"traceutil/trace.go:171","msg":"trace[238018860] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"1.034301311s","start":"2026-04-15T19:34:00.850535Z","end":"2026-04-15T19:34:01.884837Z","steps":["trace[238018860] 'process raft request' (duration: 1.034128637s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:01.884993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:00.850525Z","time spent":"1.034398623s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:34:01.885971Z","caller":"traceutil/trace.go:171","msg":"trace[879323632] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"739.795468ms","start":"2026-04-15T19:34:01.146153Z","end":"2026-04-15T19:34:01.885948Z","steps":["trace[879323632] 'process raft request' (duration: 739.553123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:01.886262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:01.146126Z","time spent":"739.90187ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:34:01.88661Z","caller":"traceutil/trace.go:171","msg":"trace[1182527033] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"702.7583ms","start":"2026-04-15T19:34:01.183838Z","end":"2026-04-15T19:34:01.886597Z","steps":["trace[1182527033] 'process raft request' (duration: 702.052376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:01.887152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:01.183816Z","time spent":"702.830502ms","remote":"127.0.0.1:41878","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-15T19:34:01.887499Z","caller":"traceutil/trace.go:171","msg":"trace[454201535] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"698.540183ms","start":"2026-04-15T19:34:01.188939Z","end":"2026-04-15T19:34:01.887479Z","steps":["trace[454201535] 'process raft request' (duration: 697.545112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:01.887639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:01.188923Z","time spent":"698.640935ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:34:01.887681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02996014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-15T19:34:01.88772Z","caller":"traceutil/trace.go:171","msg":"trace[331694364] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1986; }","duration":"1.030011862s","start":"2026-04-15T19:34:00.857699Z","end":"2026-04-15T19:34:01.887711Z","steps":["trace[331694364] 'agreement among raft nodes before linearized reading' (duration: 1.029897119s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:01.887742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:00.857686Z","time spent":"1.030051492s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-15T19:34:01.888094Z","caller":"traceutil/trace.go:171","msg":"trace[1628696123] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2066; }","duration":"1.029719585s","start":"2026-04-15T19:34:00.857714Z","end":"2026-04-15T19:34:01.887434Z","steps":["trace[1628696123] 'read index received' (duration: 1.026938897s)","trace[1628696123] 'applied index is now lower than readState.Index' (duration: 2.779408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:34:01.888295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.593674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-15T19:34:01.888327Z","caller":"traceutil/trace.go:171","msg":"trace[1879316801] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1986; }","duration":"670.649165ms","start":"2026-04-15T19:34:01.217667Z","end":"2026-04-15T19:34:01.888317Z","steps":["trace[1879316801] 'agreement among raft nodes before linearized reading' (duration: 670.509122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:01.888356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:01.217657Z","time spent":"670.693307ms","remote":"127.0.0.1:49010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-15T19:34:18.393096Z","caller":"traceutil/trace.go:171","msg":"trace[881692889] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2166; }","duration":"101.502045ms","start":"2026-04-15T19:34:18.291571Z","end":"2026-04-15T19:34:18.393073Z","steps":["trace[881692889] 'read index received' (duration: 101.292841ms)","trace[881692889] 'applied index is now lower than readState.Index' (duration: 208.244µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:18.393155Z","caller":"traceutil/trace.go:171","msg":"trace[1449488889] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"118.263066ms","start":"2026-04-15T19:34:18.274859Z","end":"2026-04-15T19:34:18.393111Z","steps":["trace[1449488889] 'process raft request' (duration: 118.071582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:18.393313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.76826ms","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-15T19:34:18.39344Z","caller":"traceutil/trace.go:171","msg":"trace[951517248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2080; }","duration":"101.886212ms","start":"2026-04-15T19:34:18.291482Z","end":"2026-04-15T19:34:18.393368Z","steps":["trace[951517248] 'agreement among raft nodes before linearized reading' (duration: 101.713119ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:24.129378Z","caller":"traceutil/trace.go:171","msg":"trace[1250593929] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"137.509497ms","start":"2026-04-15T19:34:23.99183Z","end":"2026-04-15T19:34:24.129339Z","steps":["trace[1250593929] 'process raft request' (duration: 121.249015ms)","trace[1250593929] 'compare' (duration: 16.074729ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:24.129412Z","caller":"traceutil/trace.go:171","msg":"trace[1809470421] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"128.546056ms","start":"2026-04-15T19:34:24.000827Z","end":"2026-04-15T19:34:24.129373Z","steps":["trace[1809470421] 'process raft request' (duration: 128.459174ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:24.557371Z","caller":"traceutil/trace.go:171","msg":"trace[1946165242] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2199; }","duration":"130.48322ms","start":"2026-04-15T19:34:24.426869Z","end":"2026-04-15T19:34:24.557352Z","steps":["trace[1946165242] 'read index received' (duration: 130.325477ms)","trace[1946165242] 'applied index is now lower than readState.Index' (duration: 156.673µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:24.55745Z","caller":"traceutil/trace.go:171","msg":"trace[1986102826] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"146.914504ms","start":"2026-04-15T19:34:24.410507Z","end":"2026-04-15T19:34:24.557421Z","steps":["trace[1986102826] 'process raft request' (duration: 146.70568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:24.557675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.784845ms","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-15T19:34:24.557752Z","caller":"traceutil/trace.go:171","msg":"trace[352455708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2111; }","duration":"130.900977ms","start":"2026-04-15T19:34:24.426834Z","end":"2026-04-15T19:34:24.557735Z","steps":["trace[352455708] 'agreement among raft nodes before linearized reading' (duration: 130.611861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:24.557815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.871646ms","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-15T19:34:24.557868Z","caller":"traceutil/trace.go:171","msg":"trace[1262957221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2111; }","duration":"130.953928ms","start":"2026-04-15T19:34:24.426903Z","end":"2026-04-15T19:34:24.557857Z","steps":["trace[1262957221] 'agreement among raft nodes before linearized reading' (duration: 130.756034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:24.557874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.134423ms","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-15T19:34:24.557968Z","caller":"traceutil/trace.go:171","msg":"trace[1176427265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2111; }","duration":"130.262475ms","start":"2026-04-15T19:34:24.42769Z","end":"2026-04-15T19:34:24.557952Z","steps":["trace[1176427265] 'agreement among raft nodes before linearized reading' (duration: 130.055982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:24.557949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.659263ms","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-15T19:34:24.558026Z","caller":"traceutil/trace.go:171","msg":"trace[1021861382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2111; }","duration":"130.779644ms","start":"2026-04-15T19:34:24.42723Z","end":"2026-04-15T19:34:24.55801Z","steps":["trace[1021861382] 'agreement among raft nodes before linearized reading' (duration: 130.569771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:24.55806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.619126ms","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-15T19:34:24.558092Z","caller":"traceutil/trace.go:171","msg":"trace[243758717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2111; }","duration":"123.662587ms","start":"2026-04-15T19:34:24.43442Z","end":"2026-04-15T19:34:24.558083Z","steps":["trace[243758717] 'agreement among raft nodes before linearized reading' (duration: 123.571275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:26.548589Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153698601678839839,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T19:34:26.926259Z","caller":"traceutil/trace.go:171","msg":"trace[517504910] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"884.896847ms","start":"2026-04-15T19:34:26.041339Z","end":"2026-04-15T19:34:26.926236Z","steps":["trace[517504910] 'process raft request' (duration: 884.756034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:26.926412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.04131Z","time spent":"885.022658ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:34:28.04869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000193314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-15T19:34:28.048951Z","caller":"traceutil/trace.go:171","msg":"trace[2073772834] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000473209s","start":"2026-04-15T19:34:26.048457Z","end":"2026-04-15T19:34:28.04893Z","steps":["trace[2073772834] 'agreement among raft nodes before linearized reading' (duration: 2.000190704s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.049143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.048401Z","time spent":"2.000726803s","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/15 19:34:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-15T19:34:28.091485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082803264s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T19:34:28.091707Z","caller":"traceutil/trace.go:171","msg":"trace[75503504] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2207; }","duration":"2.043217409s","start":"2026-04-15T19:34:26.048473Z","end":"2026-04-15T19:34:28.09169Z","steps":["trace[75503504] 'read index received' (duration: 877.751489ms)","trace[75503504] 'applied index is now lower than readState.Index' (duration: 1.16546508s)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:28.091938Z","caller":"traceutil/trace.go:171","msg":"trace[1963879925] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"1.955631646s","start":"2026-04-15T19:34:26.136292Z","end":"2026-04-15T19:34:28.091924Z","steps":["trace[1963879925] 'process raft request' (duration: 872.323253ms)","trace[1963879925] 'compare' (duration: 1.082326386s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:34:28.092008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.136269Z","time spent":"1.955705987s","remote":"127.0.0.1:41878","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-15T19:34:28.092176Z","caller":"traceutil/trace.go:171","msg":"trace[568819669] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"1.955226819s","start":"2026-04-15T19:34:26.13694Z","end":"2026-04-15T19:34:28.092167Z","steps":["trace[568819669] 'process raft request' (duration: 1.954637898s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.092235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.136916Z","time spent":"1.95528893s","remote":"127.0.0.1:41878","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-15T19:34:28.092352Z","caller":"traceutil/trace.go:171","msg":"trace[1436519200] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"1.529395956s","start":"2026-04-15T19:34:26.562947Z","end":"2026-04-15T19:34:28.092343Z","steps":["trace[1436519200] 'process raft request' (duration: 1.528704353s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.092405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.56293Z","time spent":"1.529449906s","remote":"127.0.0.1:41878","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-15T19:34:28.092716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.950071377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-15T19:34:28.092798Z","caller":"traceutil/trace.go:171","msg":"trace[821233263] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2121; }","duration":"1.950187369s","start":"2026-04-15T19:34:26.142589Z","end":"2026-04-15T19:34:28.092777Z","steps":["trace[821233263] 'agreement among raft nodes before linearized reading' (duration: 1.949990256s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.092839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.142577Z","time spent":"1.95025381s","remote":"127.0.0.1:49010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-15T19:34:28.093078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482264658s","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-15T19:34:28.093117Z","caller":"traceutil/trace.go:171","msg":"trace[1620192513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2121; }","duration":"1.48234882s","start":"2026-04-15T19:34:26.61076Z","end":"2026-04-15T19:34:28.093108Z","steps":["trace[1620192513] 'agreement among raft nodes before linearized reading' (duration: 1.482258298s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.093139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125339027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a69ec04832c72a\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-15T19:34:28.093186Z","caller":"traceutil/trace.go:171","msg":"trace[1076462827] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a69ec04832c72a; range_end:; response_count:1; response_revision:2121; }","duration":"1.125420329s","start":"2026-04-15T19:34:26.967754Z","end":"2026-04-15T19:34:28.093175Z","steps":["trace[1076462827] 'agreement among raft nodes before linearized reading' (duration: 1.125269807s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.093225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.890364688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T19:34:28.09322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716543545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128774"} {"level":"info","ts":"2026-04-15T19:34:28.093261Z","caller":"traceutil/trace.go:171","msg":"trace[1577314765] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2121; }","duration":"1.890431949s","start":"2026-04-15T19:34:26.202821Z","end":"2026-04-15T19:34:28.093253Z","steps":["trace[1577314765] 'agreement among raft nodes before linearized reading' (duration: 1.890384538s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.093291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.202809Z","time spent":"1.89047251s","remote":"127.0.0.1:53276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-15T19:34:28.093342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.290616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-04-15T19:34:28.093376Z","caller":"traceutil/trace.go:171","msg":"trace[1674927910] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2121; }","duration":"628.351997ms","start":"2026-04-15T19:34:27.465016Z","end":"2026-04-15T19:34:28.093368Z","steps":["trace[1674927910] 'agreement among raft nodes before linearized reading' (duration: 628.244055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.093406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:27.464993Z","time spent":"628.407778ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-15T19:34:28.093187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738954702s","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-15T19:34:28.093491Z","caller":"traceutil/trace.go:171","msg":"trace[1782684480] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2121; }","duration":"1.739282759s","start":"2026-04-15T19:34:26.354199Z","end":"2026-04-15T19:34:28.093481Z","steps":["trace[1782684480] 'agreement among raft nodes before linearized reading' (duration: 1.738963663s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.093145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.610737Z","time spent":"1.482401602s","remote":"127.0.0.1:41878","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-15T19:34:28.093525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.354186Z","time spent":"1.739329609s","remote":"127.0.0.1:45798","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-15T19:34:28.093289Z","caller":"traceutil/trace.go:171","msg":"trace[174467985] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2121; }","duration":"1.716634386s","start":"2026-04-15T19:34:26.376635Z","end":"2026-04-15T19:34:28.09327Z","steps":["trace[174467985] 'agreement among raft nodes before linearized reading' (duration: 1.716392512s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.093705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.376625Z","time spent":"1.717062564s","remote":"127.0.0.1:41686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":128797,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-15T19:34:28.093227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.967728Z","time spent":"1.12548948s","remote":"127.0.0.1:41718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a69ec04832c72a\" "} {"level":"warn","ts":"2026-04-15T19:34:28.09391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717155826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-15T19:34:28.093951Z","caller":"traceutil/trace.go:171","msg":"trace[185195616] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2121; }","duration":"1.717245707s","start":"2026-04-15T19:34:26.376694Z","end":"2026-04-15T19:34:28.09394Z","steps":["trace[185195616] 'agreement among raft nodes before linearized reading' (duration: 1.716620596s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.094489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.376678Z","time spent":"1.717794927s","remote":"127.0.0.1:41702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-15T19:34:28.094811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482739937s","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-15T19:34:28.094857Z","caller":"traceutil/trace.go:171","msg":"trace[2037779437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2121; }","duration":"1.482812809s","start":"2026-04-15T19:34:26.612034Z","end":"2026-04-15T19:34:28.094846Z","steps":["trace[2037779437] 'agreement among raft nodes before linearized reading' (duration: 1.482694377s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.09488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304278281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T19:34:28.094889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.612022Z","time spent":"1.482860249s","remote":"127.0.0.1:41878","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-15T19:34:28.094923Z","caller":"traceutil/trace.go:171","msg":"trace[1254401111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2121; }","duration":"1.304348003s","start":"2026-04-15T19:34:26.790565Z","end":"2026-04-15T19:34:28.094913Z","steps":["trace[1254401111] 'agreement among raft nodes before linearized reading' (duration: 1.304287462s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.094956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.79055Z","time spent":"1.304400053s","remote":"127.0.0.1:41650","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-15T19:34:28.095107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.519585101s","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-15T19:34:28.095167Z","caller":"traceutil/trace.go:171","msg":"trace[631857021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2121; }","duration":"1.521603216s","start":"2026-04-15T19:34:26.573549Z","end":"2026-04-15T19:34:28.095152Z","steps":["trace[631857021] 'agreement among raft nodes before linearized reading' (duration: 1.519497799s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.095207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.573537Z","time spent":"1.521659157s","remote":"127.0.0.1:41878","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-15T19:34:28.096393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481933023s","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-15T19:34:28.096463Z","caller":"traceutil/trace.go:171","msg":"trace[486213020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2121; }","duration":"1.485012367s","start":"2026-04-15T19:34:26.611436Z","end":"2026-04-15T19:34:28.096448Z","steps":["trace[486213020] 'agreement among raft nodes before linearized reading' (duration: 1.481559286s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.097595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483732334s","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-15T19:34:28.097895Z","caller":"traceutil/trace.go:171","msg":"trace[1421984788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2121; }","duration":"1.486023065s","start":"2026-04-15T19:34:26.611834Z","end":"2026-04-15T19:34:28.097857Z","steps":["trace[1421984788] 'agreement among raft nodes before linearized reading' (duration: 1.482959311s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.097962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.611812Z","time spent":"1.486132607s","remote":"127.0.0.1:41878","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-15T19:34:28.096515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:26.611425Z","time spent":"1.485075789s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T19:34:28.601551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153698601678839846,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T19:34:28.632003Z","caller":"traceutil/trace.go:171","msg":"trace[2134021121] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"531.190289ms","start":"2026-04-15T19:34:28.100789Z","end":"2026-04-15T19:34:28.631979Z","steps":["trace[2134021121] 'process raft request' (duration: 531.055087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:28.63236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.100773Z","time spent":"531.397313ms","remote":"127.0.0.1:41718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:34:29.239126Z","caller":"traceutil/trace.go:171","msg":"trace[299445462] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"1.138055905s","start":"2026-04-15T19:34:28.101044Z","end":"2026-04-15T19:34:29.2391Z","steps":["trace[299445462] 'process raft request' (duration: 1.131688754s)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:29.239263Z","caller":"traceutil/trace.go:171","msg":"trace[1349239743] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"1.137584128s","start":"2026-04-15T19:34:28.10166Z","end":"2026-04-15T19:34:29.239244Z","steps":["trace[1349239743] 'process raft request' (duration: 1.136929087s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.239347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.101039Z","time spent":"1.138229379s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:34:29.239354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.101653Z","time spent":"1.137651549s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:34:29.239523Z","caller":"traceutil/trace.go:171","msg":"trace[1565284176] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"1.137846813s","start":"2026-04-15T19:34:28.101666Z","end":"2026-04-15T19:34:29.239513Z","steps":["trace[1565284176] 'process raft request' (duration: 1.137529657s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.239606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.10163Z","time spent":"1.137943714s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:34:29.239937Z","caller":"traceutil/trace.go:171","msg":"trace[1578807211] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"1.137453555s","start":"2026-04-15T19:34:28.102449Z","end":"2026-04-15T19:34:29.239903Z","steps":["trace[1578807211] 'process raft request' (duration: 1.137024208s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.24008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.102439Z","time spent":"1.137560257s","remote":"127.0.0.1:41878","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-15T19:34:29.240154Z","caller":"traceutil/trace.go:171","msg":"trace[1555240319] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2211; }","duration":"1.139244906s","start":"2026-04-15T19:34:28.10089Z","end":"2026-04-15T19:34:29.240135Z","steps":["trace[1555240319] 'read index received' (duration: 531.494795ms)","trace[1555240319] 'applied index is now lower than readState.Index' (duration: 607.747881ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:34:29.240176Z","caller":"traceutil/trace.go:171","msg":"trace[1490608214] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"459.268097ms","start":"2026-04-15T19:34:28.780872Z","end":"2026-04-15T19:34:29.24014Z","steps":["trace[1490608214] 'process raft request' (duration: 459.145114ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:29.240264Z","caller":"traceutil/trace.go:171","msg":"trace[801542848] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"308.626163ms","start":"2026-04-15T19:34:28.931607Z","end":"2026-04-15T19:34:29.240233Z","steps":["trace[801542848] 'process raft request' (duration: 308.4699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.240379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.780849Z","time spent":"459.419369ms","remote":"127.0.0.1:41878","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-15T19:34:29.240425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.931576Z","time spent":"308.768426ms","remote":"127.0.0.1:41878","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-15T19:34:29.240482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139580802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25777"} {"level":"info","ts":"2026-04-15T19:34:29.240517Z","caller":"traceutil/trace.go:171","msg":"trace[1942705019] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2130; }","duration":"1.139621983s","start":"2026-04-15T19:34:28.100885Z","end":"2026-04-15T19:34:29.240507Z","steps":["trace[1942705019] 'agreement among raft nodes before linearized reading' (duration: 1.139343798s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.240553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.100849Z","time spent":"1.139696005s","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":15,"response size":25800,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-04-15T19:34:29.240663Z","caller":"traceutil/trace.go:171","msg":"trace[1975030841] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"1.136865355s","start":"2026-04-15T19:34:28.103132Z","end":"2026-04-15T19:34:29.239998Z","steps":["trace[1975030841] 'process raft request' (duration: 1.13658628s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.240788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.217624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-15T19:34:29.24083Z","caller":"traceutil/trace.go:171","msg":"trace[61734553] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:2130; }","duration":"374.286865ms","start":"2026-04-15T19:34:28.866532Z","end":"2026-04-15T19:34:29.240818Z","steps":["trace[61734553] 'agreement among raft nodes before linearized reading' (duration: 374.190644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.240873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.866518Z","time spent":"374.336726ms","remote":"127.0.0.1:41672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"warn","ts":"2026-04-15T19:34:29.240898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139937438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25777"} {"level":"info","ts":"2026-04-15T19:34:29.240953Z","caller":"traceutil/trace.go:171","msg":"trace[1922604802] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2130; }","duration":"1.140015231s","start":"2026-04-15T19:34:28.100927Z","end":"2026-04-15T19:34:29.240943Z","steps":["trace[1922604802] 'agreement among raft nodes before linearized reading' (duration: 1.139473101s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.240991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.100922Z","time spent":"1.140059931s","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":15,"response size":25800,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-04-15T19:34:29.241025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135716735s","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-15T19:34:29.241058Z","caller":"traceutil/trace.go:171","msg":"trace[900590112] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2130; }","duration":"1.135764035s","start":"2026-04-15T19:34:28.105285Z","end":"2026-04-15T19:34:29.241049Z","steps":["trace[900590112] 'agreement among raft nodes before linearized reading' (duration: 1.135710694s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.241083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.10528Z","time spent":"1.135796816s","remote":"127.0.0.1:42056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-15T19:34:29.241142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.103124Z","time spent":"1.137681229s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:34:29.241227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137889412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:34:29.241261Z","caller":"traceutil/trace.go:171","msg":"trace[753760514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2130; }","duration":"1.137951494s","start":"2026-04-15T19:34:28.103301Z","end":"2026-04-15T19:34:29.241253Z","steps":["trace[753760514] 'agreement among raft nodes before linearized reading' (duration: 1.137901383s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.241283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.103287Z","time spent":"1.137990714s","remote":"127.0.0.1:41646","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-15T19:34:29.240699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.622219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-15T19:34:29.24144Z","caller":"traceutil/trace.go:171","msg":"trace[2087491210] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2130; }","duration":"139.394943ms","start":"2026-04-15T19:34:29.102024Z","end":"2026-04-15T19:34:29.241419Z","steps":["trace[2087491210] 'agreement among raft nodes before linearized reading' (duration: 138.560758ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:29.24158Z","caller":"traceutil/trace.go:171","msg":"trace[1638035104] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"605.215417ms","start":"2026-04-15T19:34:28.636351Z","end":"2026-04-15T19:34:29.241567Z","steps":["trace[1638035104] 'process raft request' (duration: 603.593889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:34:29.241684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:34:28.636323Z","time spent":"605.300188ms","remote":"127.0.0.1:41718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-15T19:34:36.256211Z","caller":"traceutil/trace.go:171","msg":"trace[1293067864] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"137.294262ms","start":"2026-04-15T19:34:36.11889Z","end":"2026-04-15T19:34:36.256185Z","steps":["trace[1293067864] 'process raft request' (duration: 137.246631ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:36.256229Z","caller":"traceutil/trace.go:171","msg":"trace[294539378] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"137.555036ms","start":"2026-04-15T19:34:36.118645Z","end":"2026-04-15T19:34:36.2562Z","steps":["trace[294539378] 'process raft request' (duration: 137.219431ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:48.405716Z","caller":"traceutil/trace.go:171","msg":"trace[1873517078] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"100.624703ms","start":"2026-04-15T19:34:48.305051Z","end":"2026-04-15T19:34:48.405675Z","steps":["trace[1873517078] 'process raft request' (duration: 100.44276ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:34:50.048709Z","caller":"traceutil/trace.go:171","msg":"trace[1284139696] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"142.346805ms","start":"2026-04-15T19:34:49.906307Z","end":"2026-04-15T19:34:50.048653Z","steps":["trace[1284139696] 'process raft request' (duration: 142.089801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:04.538412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.413794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T19:35:04.538478Z","caller":"traceutil/trace.go:171","msg":"trace[1252235296] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2369; }","duration":"686.531526ms","start":"2026-04-15T19:35:03.85193Z","end":"2026-04-15T19:35:04.538461Z","steps":["trace[1252235296] 'count revisions from in-memory index tree' (duration: 686.279132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:04.53904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:03.851897Z","time spent":"686.603636ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":4,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-04-15T19:35:04.547673Z","caller":"traceutil/trace.go:171","msg":"trace[699661065] transaction","detail":"{read_only:false; response_revision:2370; number_of_response:1; }","duration":"316.450223ms","start":"2026-04-15T19:35:04.231169Z","end":"2026-04-15T19:35:04.547619Z","steps":["trace[699661065] 'process raft request' (duration: 316.125818ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:35:04.547781Z","caller":"traceutil/trace.go:171","msg":"trace[1152528900] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"185.988112ms","start":"2026-04-15T19:35:04.361762Z","end":"2026-04-15T19:35:04.54775Z","steps":["trace[1152528900] 'process raft request' (duration: 185.676807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:04.547867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:04.231148Z","time spent":"316.618816ms","remote":"127.0.0.1:41878","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-15T19:35:04.548103Z","caller":"traceutil/trace.go:171","msg":"trace[400039330] linearizableReadLoop","detail":"{readStateIndex:2469; appliedIndex:2467; }","duration":"280.022098ms","start":"2026-04-15T19:35:04.26807Z","end":"2026-04-15T19:35:04.548092Z","steps":["trace[400039330] 'read index received' (duration: 267.978178ms)","trace[400039330] 'applied index is now lower than readState.Index' (duration: 12.04245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:35:04.548276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.197611ms","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-15T19:35:04.548297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.252212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:35:04.548311Z","caller":"traceutil/trace.go:171","msg":"trace[77760659] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2372; }","duration":"280.253562ms","start":"2026-04-15T19:35:04.26805Z","end":"2026-04-15T19:35:04.548304Z","steps":["trace[77760659] 'agreement among raft nodes before linearized reading' (duration: 280.168751ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:35:04.548376Z","caller":"traceutil/trace.go:171","msg":"trace[461835522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2372; }","duration":"268.363644ms","start":"2026-04-15T19:35:04.27999Z","end":"2026-04-15T19:35:04.548354Z","steps":["trace[461835522] 'agreement among raft nodes before linearized reading' (duration: 268.236572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:13.291877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153698601678840616,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T19:35:13.326731Z","caller":"traceutil/trace.go:171","msg":"trace[671507822] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"749.623355ms","start":"2026-04-15T19:35:12.577088Z","end":"2026-04-15T19:35:13.326711Z","steps":["trace[671507822] 'process raft request' (duration: 749.520323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:13.326843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:12.577065Z","time spent":"749.723146ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:35:13.339339Z","caller":"traceutil/trace.go:171","msg":"trace[1563877984] linearizableReadLoop","detail":"{readStateIndex:2570; appliedIndex:2568; }","duration":"547.846788ms","start":"2026-04-15T19:35:12.791471Z","end":"2026-04-15T19:35:13.339318Z","steps":["trace[1563877984] 'read index received' (duration: 535.59234ms)","trace[1563877984] 'applied index is now lower than readState.Index' (duration: 12.253498ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:35:13.339712Z","caller":"traceutil/trace.go:171","msg":"trace[79459203] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"757.118733ms","start":"2026-04-15T19:35:12.58258Z","end":"2026-04-15T19:35:13.339699Z","steps":["trace[79459203] 'process raft request' (duration: 756.585076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:13.339809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:12.582567Z","time spent":"757.174254ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:35:13.339994Z","caller":"traceutil/trace.go:171","msg":"trace[438706123] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"756.076488ms","start":"2026-04-15T19:35:12.583894Z","end":"2026-04-15T19:35:13.339971Z","steps":["trace[438706123] 'process raft request' (duration: 755.385118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:13.340047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:12.583868Z","time spent":"756.149519ms","remote":"127.0.0.1:41878","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-15T19:35:13.340173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.68388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:35:13.340219Z","caller":"traceutil/trace.go:171","msg":"trace[1515700319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2470; }","duration":"548.762361ms","start":"2026-04-15T19:35:12.79144Z","end":"2026-04-15T19:35:13.340203Z","steps":["trace[1515700319] 'agreement among raft nodes before linearized reading' (duration: 548.68564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:13.340247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:12.791427Z","time spent":"548.814851ms","remote":"127.0.0.1:41646","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-15T19:35:39.945288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.923209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:35:39.945396Z","caller":"traceutil/trace.go:171","msg":"trace[1464619332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2623; }","duration":"156.09345ms","start":"2026-04-15T19:35:39.78926Z","end":"2026-04-15T19:35:39.945354Z","steps":["trace[1464619332] 'range keys from in-memory index tree' (duration: 155.838487ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:35:45.976218Z","caller":"traceutil/trace.go:171","msg":"trace[719243018] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"529.2598ms","start":"2026-04-15T19:35:45.446935Z","end":"2026-04-15T19:35:45.976195Z","steps":["trace[719243018] 'process raft request' (duration: 529.21418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:45.976357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:45.446928Z","time spent":"529.355292ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:35:45.976604Z","caller":"traceutil/trace.go:171","msg":"trace[359131399] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"529.681076ms","start":"2026-04-15T19:35:45.446895Z","end":"2026-04-15T19:35:45.976576Z","steps":["trace[359131399] 'process raft request' (duration: 529.144248ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:35:45.976633Z","caller":"traceutil/trace.go:171","msg":"trace[2048886177] linearizableReadLoop","detail":"{readStateIndex:2755; appliedIndex:2755; }","duration":"488.424695ms","start":"2026-04-15T19:35:45.488184Z","end":"2026-04-15T19:35:45.976608Z","steps":["trace[2048886177] 'read index received' (duration: 488.416235ms)","trace[2048886177] 'applied index is now lower than readState.Index' (duration: 7.24µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:35:45.976803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:45.446888Z","time spent":"529.773877ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:35:45.977051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.849251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a69ed174a61b67\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-15T19:35:45.977112Z","caller":"traceutil/trace.go:171","msg":"trace[340704789] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a69ed174a61b67; range_end:; response_count:1; response_revision:2647; }","duration":"488.950393ms","start":"2026-04-15T19:35:45.488151Z","end":"2026-04-15T19:35:45.977101Z","steps":["trace[340704789] 'agreement among raft nodes before linearized reading' (duration: 488.630298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:45.977246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:45.488137Z","time spent":"489.096394ms","remote":"127.0.0.1:41718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18a69ed174a61b67\" "} {"level":"info","ts":"2026-04-15T19:35:45.998946Z","caller":"traceutil/trace.go:171","msg":"trace[1394484709] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"433.038857ms","start":"2026-04-15T19:35:45.565884Z","end":"2026-04-15T19:35:45.998923Z","steps":["trace[1394484709] 'process raft request' (duration: 432.812414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:45.998959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.792415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T19:35:45.999056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:45.565864Z","time spent":"433.131378ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:35:45.999146Z","caller":"traceutil/trace.go:171","msg":"trace[954229840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2648; }","duration":"208.885827ms","start":"2026-04-15T19:35:45.790113Z","end":"2026-04-15T19:35:45.998999Z","steps":["trace[954229840] 'agreement among raft nodes before linearized reading' (duration: 208.781946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:45.999615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.606357ms","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-15T19:35:45.999679Z","caller":"traceutil/trace.go:171","msg":"trace[442013536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2648; }","duration":"341.709879ms","start":"2026-04-15T19:35:45.657955Z","end":"2026-04-15T19:35:45.999665Z","steps":["trace[442013536] 'agreement among raft nodes before linearized reading' (duration: 340.898268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:45.999697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.532742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-15T19:35:45.999722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:45.657933Z","time spent":"341.772289ms","remote":"127.0.0.1:41878","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":"info","ts":"2026-04-15T19:35:45.999743Z","caller":"traceutil/trace.go:171","msg":"trace[756539579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2648; }","duration":"322.596794ms","start":"2026-04-15T19:35:45.677132Z","end":"2026-04-15T19:35:45.999729Z","steps":["trace[756539579] 'agreement among raft nodes before linearized reading' (duration: 322.478792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:45.99979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:45.67712Z","time spent":"322.660174ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T19:35:45.999779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.269686ms","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-15T19:35:45.999854Z","caller":"traceutil/trace.go:171","msg":"trace[1961960742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2648; }","duration":"321.384647ms","start":"2026-04-15T19:35:45.678449Z","end":"2026-04-15T19:35:45.999834Z","steps":["trace[1961960742] 'agreement among raft nodes before linearized reading' (duration: 321.164924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:45.999912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:45.678432Z","time spent":"321.468898ms","remote":"127.0.0.1:41878","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-15T19:35:45.999933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.907307ms","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-15T19:35:45.99996Z","caller":"traceutil/trace.go:171","msg":"trace[1909492731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2648; }","duration":"334.955849ms","start":"2026-04-15T19:35:45.664996Z","end":"2026-04-15T19:35:45.999952Z","steps":["trace[1909492731] 'agreement among raft nodes before linearized reading' (duration: 334.876788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:45.999992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:45.664986Z","time spent":"335.000939ms","remote":"127.0.0.1:41878","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-15T19:35:46.000118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.396031ms","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-15T19:35:46.000163Z","caller":"traceutil/trace.go:171","msg":"trace[1865867431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2648; }","duration":"331.468151ms","start":"2026-04-15T19:35:45.668685Z","end":"2026-04-15T19:35:46.000153Z","steps":["trace[1865867431] 'agreement among raft nodes before linearized reading' (duration: 331.36526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:46.000196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:45.668668Z","time spent":"331.521443ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T19:35:47.780108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:47.298656Z","time spent":"481.445768ms","remote":"127.0.0.1:45338","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-15T19:35:47.780908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667458389s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T19:35:47.781068Z","caller":"traceutil/trace.go:171","msg":"trace[2134448051] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"1.776559092s","start":"2026-04-15T19:35:46.004487Z","end":"2026-04-15T19:35:47.781046Z","steps":["trace[2134448051] 'process raft request' (duration: 108.294662ms)","trace[2134448051] 'compare' (duration: 1.666586528s)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:35:47.781157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:46.004468Z","time spent":"1.776644433s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:35:47.782215Z","caller":"traceutil/trace.go:171","msg":"trace[2027073372] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"1.777617226s","start":"2026-04-15T19:35:46.004572Z","end":"2026-04-15T19:35:47.78219Z","steps":["trace[2027073372] 'process raft request' (duration: 1.77641081s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:47.782362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:46.004566Z","time spent":"1.777721308s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:35:47.782413Z","caller":"traceutil/trace.go:171","msg":"trace[50495769] transaction","detail":"{read_only:false; response_revision:2653; number_of_response:1; }","duration":"1.777509125s","start":"2026-04-15T19:35:46.00489Z","end":"2026-04-15T19:35:47.782399Z","steps":["trace[50495769] 'process raft request' (duration: 1.777397644s)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:35:47.782452Z","caller":"traceutil/trace.go:171","msg":"trace[790337084] transaction","detail":"{read_only:false; response_revision:2652; number_of_response:1; }","duration":"1.777814258s","start":"2026-04-15T19:35:46.004618Z","end":"2026-04-15T19:35:47.782433Z","steps":["trace[790337084] 'process raft request' (duration: 1.777463734s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:47.782499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:46.004874Z","time spent":"1.777574086s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:35:47.782522Z","caller":"traceutil/trace.go:171","msg":"trace[2099584515] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"891.037044ms","start":"2026-04-15T19:35:46.891469Z","end":"2026-04-15T19:35:47.782506Z","steps":["trace[2099584515] 'process raft request' (duration: 890.989294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:47.782579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:46.004613Z","time spent":"1.777906311s","remote":"127.0.0.1:41878","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-15T19:35:47.782598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:46.891444Z","time spent":"891.114535ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:35:47.782652Z","caller":"traceutil/trace.go:171","msg":"trace[1216634459] linearizableReadLoop","detail":"{readStateIndex:2762; appliedIndex:2757; }","duration":"1.591426637s","start":"2026-04-15T19:35:46.191204Z","end":"2026-04-15T19:35:47.78263Z","steps":["trace[1216634459] 'read index received' (duration: 55.381µs)","trace[1216634459] 'applied index is now lower than readState.Index' (duration: 1.591368236s)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:35:47.782773Z","caller":"traceutil/trace.go:171","msg":"trace[1543655001] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"1.776231678s","start":"2026-04-15T19:35:46.006255Z","end":"2026-04-15T19:35:47.782487Z","steps":["trace[1543655001] 'process raft request' (duration: 1.776120637s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:47.782874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59166866s","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-15T19:35:47.783069Z","caller":"traceutil/trace.go:171","msg":"trace[2065458446] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2655; }","duration":"1.591878763s","start":"2026-04-15T19:35:46.191173Z","end":"2026-04-15T19:35:47.783052Z","steps":["trace[2065458446] 'agreement among raft nodes before linearized reading' (duration: 1.591529288s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:47.783111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:46.191162Z","time spent":"1.591940103s","remote":"127.0.0.1:41824","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-15T19:35:47.783198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:46.006248Z","time spent":"1.776718845s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:35:48.003441Z","caller":"traceutil/trace.go:171","msg":"trace[952031649] linearizableReadLoop","detail":"{readStateIndex:2764; appliedIndex:2763; }","duration":"220.707532ms","start":"2026-04-15T19:35:47.782705Z","end":"2026-04-15T19:35:48.003413Z","steps":["trace[952031649] 'read index received' (duration: 213.267453ms)","trace[952031649] 'applied index is now lower than readState.Index' (duration: 7.439109ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:35:48.003565Z","caller":"traceutil/trace.go:171","msg":"trace[1067407212] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"527.6533ms","start":"2026-04-15T19:35:47.475895Z","end":"2026-04-15T19:35:48.003548Z","steps":["trace[1067407212] 'process raft request' (duration: 519.988649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:48.003657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:47.475863Z","time spent":"527.726682ms","remote":"127.0.0.1:41878","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-15T19:35:48.003843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045000837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-15T19:35:48.003887Z","caller":"traceutil/trace.go:171","msg":"trace[1502505227] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2658; }","duration":"1.045062579s","start":"2026-04-15T19:35:46.958814Z","end":"2026-04-15T19:35:48.003877Z","steps":["trace[1502505227] 'agreement among raft nodes before linearized reading' (duration: 1.044941537s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:48.003914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:46.958804Z","time spent":"1.045103489s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-15T19:35:48.004028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048512254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-04-15T19:35:48.00404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211505243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:35:48.004059Z","caller":"traceutil/trace.go:171","msg":"trace[1508774006] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2658; }","duration":"1.048583855s","start":"2026-04-15T19:35:46.955468Z","end":"2026-04-15T19:35:48.004052Z","steps":["trace[1508774006] 'agreement among raft nodes before linearized reading' (duration: 1.048505364s)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:35:48.00407Z","caller":"traceutil/trace.go:171","msg":"trace[2080028411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2658; }","duration":"1.211572484s","start":"2026-04-15T19:35:46.792488Z","end":"2026-04-15T19:35:48.004061Z","steps":["trace[2080028411] 'agreement among raft nodes before linearized reading' (duration: 1.211524864s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:35:48.004085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:46.955448Z","time spent":"1.048631376s","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-15T19:35:48.004091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:35:46.792467Z","time spent":"1.211618995s","remote":"127.0.0.1:41646","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-15T19:35:48.004189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.965366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-15T19:35:48.004238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.182625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-04-15T19:35:48.004297Z","caller":"traceutil/trace.go:171","msg":"trace[2064961248] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2658; }","duration":"206.106007ms","start":"2026-04-15T19:35:47.798176Z","end":"2026-04-15T19:35:48.004282Z","steps":["trace[2064961248] 'agreement among raft nodes before linearized reading' (duration: 205.917705ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:35:48.004322Z","caller":"traceutil/trace.go:171","msg":"trace[510471197] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2658; }","duration":"217.285836ms","start":"2026-04-15T19:35:47.787008Z","end":"2026-04-15T19:35:48.004294Z","steps":["trace[510471197] 'agreement among raft nodes before linearized reading' (duration: 217.036163ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:37:52.120961Z","caller":"traceutil/trace.go:171","msg":"trace[372798861] transaction","detail":"{read_only:false; response_revision:3396; number_of_response:1; }","duration":"109.797451ms","start":"2026-04-15T19:37:52.01113Z","end":"2026-04-15T19:37:52.120928Z","steps":["trace[372798861] 'process raft request' (duration: 109.569834ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:30.997759Z","caller":"traceutil/trace.go:171","msg":"trace[1792850073] linearizableReadLoop","detail":"{readStateIndex:4784; appliedIndex:4783; }","duration":"207.258717ms","start":"2026-04-15T19:39:30.790478Z","end":"2026-04-15T19:39:30.997737Z","steps":["trace[1792850073] 'read index received' (duration: 207.101184ms)","trace[1792850073] 'applied index is now lower than readState.Index' (duration: 156.763µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:39:30.997858Z","caller":"traceutil/trace.go:171","msg":"trace[1431077506] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"210.108418ms","start":"2026-04-15T19:39:30.787739Z","end":"2026-04-15T19:39:30.997848Z","steps":["trace[1431077506] 'process raft request' (duration: 209.840353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:30.998264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.711347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-15T19:39:30.998446Z","caller":"traceutil/trace.go:171","msg":"trace[1741162874] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4601; }","duration":"207.989772ms","start":"2026-04-15T19:39:30.790435Z","end":"2026-04-15T19:39:30.998425Z","steps":["trace[1741162874] 'agreement among raft nodes before linearized reading' (duration: 207.476831ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:31.142201Z","caller":"traceutil/trace.go:171","msg":"trace[166021203] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"344.178849ms","start":"2026-04-15T19:39:30.797996Z","end":"2026-04-15T19:39:31.142175Z","steps":["trace[166021203] 'process raft request' (duration: 343.943693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:31.142355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:30.797982Z","time spent":"344.296461ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:39:31.142539Z","caller":"traceutil/trace.go:171","msg":"trace[327375757] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"343.687718ms","start":"2026-04-15T19:39:30.798827Z","end":"2026-04-15T19:39:31.142514Z","steps":["trace[327375757] 'process raft request' (duration: 343.290889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:31.142673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:30.798817Z","time spent":"343.78735ms","remote":"127.0.0.1:41878","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-15T19:39:31.142859Z","caller":"traceutil/trace.go:171","msg":"trace[1172981060] linearizableReadLoop","detail":"{readStateIndex:4787; appliedIndex:4784; }","duration":"145.023029ms","start":"2026-04-15T19:39:30.997824Z","end":"2026-04-15T19:39:31.142847Z","steps":["trace[1172981060] 'read index received' (duration: 144.085919ms)","trace[1172981060] 'applied index is now lower than readState.Index' (duration: 936.18µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:39:31.143056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.872704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-15T19:39:31.143103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.737853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-15T19:39:31.14314Z","caller":"traceutil/trace.go:171","msg":"trace[1875124112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4604; }","duration":"285.789223ms","start":"2026-04-15T19:39:30.857342Z","end":"2026-04-15T19:39:31.143131Z","steps":["trace[1875124112] 'agreement among raft nodes before linearized reading' (duration: 285.661731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:31.14318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.612098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-15T19:39:31.143211Z","caller":"traceutil/trace.go:171","msg":"trace[1493574024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4604; }","duration":"252.68925ms","start":"2026-04-15T19:39:30.890515Z","end":"2026-04-15T19:39:31.143204Z","steps":["trace[1493574024] 'agreement among raft nodes before linearized reading' (duration: 252.612608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:31.143294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.212186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"warn","ts":"2026-04-15T19:39:31.143329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.359459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T19:39:31.143354Z","caller":"traceutil/trace.go:171","msg":"trace[247899055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4604; }","duration":"255.41165ms","start":"2026-04-15T19:39:30.887936Z","end":"2026-04-15T19:39:31.143348Z","steps":["trace[247899055] 'agreement among raft nodes before linearized reading' (duration: 255.345519ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:31.143328Z","caller":"traceutil/trace.go:171","msg":"trace[1818250296] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4604; }","duration":"288.281978ms","start":"2026-04-15T19:39:30.855037Z","end":"2026-04-15T19:39:31.143319Z","steps":["trace[1818250296] 'agreement among raft nodes before linearized reading' (duration: 288.179915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:31.143402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.362909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-75bcd48d96-lvspq.18a69f0a59866966\" ","response":"range_response_count:1 size:896"} {"level":"warn","ts":"2026-04-15T19:39:31.143486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.282155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-15T19:39:31.143528Z","caller":"traceutil/trace.go:171","msg":"trace[1303492814] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4604; }","duration":"117.334286ms","start":"2026-04-15T19:39:31.026169Z","end":"2026-04-15T19:39:31.143504Z","steps":["trace[1303492814] 'agreement among raft nodes before linearized reading' (duration: 117.260344ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:31.143536Z","caller":"traceutil/trace.go:171","msg":"trace[247663138] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-75bcd48d96-lvspq.18a69f0a59866966; range_end:; response_count:1; response_revision:4604; }","duration":"288.496202ms","start":"2026-04-15T19:39:30.854996Z","end":"2026-04-15T19:39:31.143492Z","steps":["trace[247663138] 'agreement among raft nodes before linearized reading' (duration: 288.350569ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:31.14358Z","caller":"traceutil/trace.go:171","msg":"trace[1379027336] transaction","detail":"{read_only:false; response_revision:4604; number_of_response:1; }","duration":"341.749796ms","start":"2026-04-15T19:39:30.801773Z","end":"2026-04-15T19:39:31.143523Z","steps":["trace[1379027336] 'process raft request' (duration: 340.667952ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:31.143108Z","caller":"traceutil/trace.go:171","msg":"trace[1749340523] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4604; }","duration":"257.944286ms","start":"2026-04-15T19:39:30.885151Z","end":"2026-04-15T19:39:31.143095Z","steps":["trace[1749340523] 'agreement among raft nodes before linearized reading' (duration: 257.791042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:31.143845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:30.801765Z","time spent":"341.9229ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:39:31.143866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.664096ms","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-15T19:39:31.143929Z","caller":"traceutil/trace.go:171","msg":"trace[874947717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4604; }","duration":"250.747649ms","start":"2026-04-15T19:39:30.893166Z","end":"2026-04-15T19:39:31.143914Z","steps":["trace[874947717] 'agreement among raft nodes before linearized reading' (duration: 250.461162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:31.144318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.387874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-15T19:39:31.144802Z","caller":"traceutil/trace.go:171","msg":"trace[93158672] 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:4604; }","duration":"252.566938ms","start":"2026-04-15T19:39:30.891833Z","end":"2026-04-15T19:39:31.1444Z","steps":["trace[93158672] 'agreement among raft nodes before linearized reading' (duration: 251.27448ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:31.504585Z","caller":"traceutil/trace.go:171","msg":"trace[1962215932] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"173.810736ms","start":"2026-04-15T19:39:31.330737Z","end":"2026-04-15T19:39:31.504548Z","steps":["trace[1962215932] 'process raft request' (duration: 173.51857ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:31.511835Z","caller":"traceutil/trace.go:171","msg":"trace[1762978208] transaction","detail":"{read_only:false; response_revision:4617; number_of_response:1; }","duration":"179.352527ms","start":"2026-04-15T19:39:31.332451Z","end":"2026-04-15T19:39:31.511803Z","steps":["trace[1762978208] 'process raft request' (duration: 179.191584ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:31.980706Z","caller":"traceutil/trace.go:171","msg":"trace[41486715] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"102.307799ms","start":"2026-04-15T19:39:31.878366Z","end":"2026-04-15T19:39:31.980674Z","steps":["trace[41486715] 'process raft request' (duration: 101.709315ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:36.995534Z","caller":"traceutil/trace.go:171","msg":"trace[615903706] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"122.074454ms","start":"2026-04-15T19:39:36.873437Z","end":"2026-04-15T19:39:36.995511Z","steps":["trace[615903706] 'process raft request' (duration: 117.546837ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:40.603636Z","caller":"traceutil/trace.go:171","msg":"trace[44185530] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"304.085152ms","start":"2026-04-15T19:39:40.299524Z","end":"2026-04-15T19:39:40.603609Z","steps":["trace[44185530] 'process raft request' (duration: 303.888668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:40.603812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:40.299504Z","time spent":"304.221986ms","remote":"127.0.0.1:41800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11244,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:39:40.973485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.124527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:39:40.973868Z","caller":"traceutil/trace.go:171","msg":"trace[458257526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4748; }","duration":"182.561176ms","start":"2026-04-15T19:39:40.791287Z","end":"2026-04-15T19:39:40.973848Z","steps":["trace[458257526] 'range keys from in-memory index tree' (duration: 182.036775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:46.59876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153698601678846244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T19:39:47.077302Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.05298677s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T19:39:47.07783Z","caller":"traceutil/trace.go:171","msg":"trace[2047651954] linearizableReadLoop","detail":"{readStateIndex:4985; appliedIndex:4984; }","duration":"979.803156ms","start":"2026-04-15T19:39:46.098002Z","end":"2026-04-15T19:39:47.077806Z","steps":["trace[2047651954] 'read index received' (duration: 979.475739ms)","trace[2047651954] 'applied index is now lower than readState.Index' (duration: 326.427µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:39:47.078019Z","caller":"traceutil/trace.go:171","msg":"trace[699847990] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"1.053777577s","start":"2026-04-15T19:39:46.024152Z","end":"2026-04-15T19:39:47.07793Z","steps":["trace[699847990] 'process raft request' (duration: 1.05344774s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.078157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.149993ms","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":"warn","ts":"2026-04-15T19:39:47.078161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:46.024111Z","time spent":"1.053949521s","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13863,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:39:47.078207Z","caller":"traceutil/trace.go:171","msg":"trace[213360275] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4795; }","duration":"980.263645ms","start":"2026-04-15T19:39:46.097927Z","end":"2026-04-15T19:39:47.078191Z","steps":["trace[213360275] 'agreement among raft nodes before linearized reading' (duration: 980.097301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.07824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:46.097902Z","time spent":"980.331957ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-15T19:39:47.540008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.09346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T19:39:47.540293Z","caller":"traceutil/trace.go:171","msg":"trace[303759681] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"343.845615ms","start":"2026-04-15T19:39:47.196427Z","end":"2026-04-15T19:39:47.540273Z","steps":["trace[303759681] 'process raft request' (duration: 343.809654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.54037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:47.196411Z","time spent":"343.926126ms","remote":"127.0.0.1:41878","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-15T19:39:47.540578Z","caller":"traceutil/trace.go:171","msg":"trace[1927150945] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"345.033389ms","start":"2026-04-15T19:39:47.195534Z","end":"2026-04-15T19:39:47.540567Z","steps":["trace[1927150945] 'process raft request' (duration: 344.637341ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:47.54057Z","caller":"traceutil/trace.go:171","msg":"trace[331864810] linearizableReadLoop","detail":"{readStateIndex:4987; appliedIndex:4985; }","duration":"462.639599ms","start":"2026-04-15T19:39:47.077893Z","end":"2026-04-15T19:39:47.540533Z","steps":["trace[331864810] 'read index received' (duration: 204.942697ms)","trace[331864810] 'applied index is now lower than readState.Index' (duration: 257.689292ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:39:47.540637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:47.195495Z","time spent":"345.114021ms","remote":"127.0.0.1:41878","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-15T19:39:47.54079Z","caller":"traceutil/trace.go:171","msg":"trace[1447901287] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"345.008648ms","start":"2026-04-15T19:39:47.195664Z","end":"2026-04-15T19:39:47.540673Z","steps":["trace[1447901287] 'process raft request' (duration: 344.542229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.540943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.653832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:39:47.54099Z","caller":"traceutil/trace.go:171","msg":"trace[2027461973] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:4800; }","duration":"673.739914ms","start":"2026-04-15T19:39:46.867236Z","end":"2026-04-15T19:39:47.540976Z","steps":["trace[2027461973] 'agreement among raft nodes before linearized reading' (duration: 673.671222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.541031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:46.867217Z","time spent":"673.801326ms","remote":"127.0.0.1:37224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"info","ts":"2026-04-15T19:39:47.541182Z","caller":"traceutil/trace.go:171","msg":"trace[1138255987] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"907.305702ms","start":"2026-04-15T19:39:46.633865Z","end":"2026-04-15T19:39:47.541171Z","steps":["trace[1138255987] 'process raft request' (duration: 648.958706ms)","trace[1138255987] 'compare' (duration: 256.873555ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:39:47.541196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.418102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13878"} {"level":"warn","ts":"2026-04-15T19:39:47.541243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:46.633841Z","time spent":"907.370694ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T19:39:47.54125Z","caller":"traceutil/trace.go:171","msg":"trace[1121855265] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4800; }","duration":"458.512394ms","start":"2026-04-15T19:39:47.08272Z","end":"2026-04-15T19:39:47.541233Z","steps":["trace[1121855265] 'agreement among raft nodes before linearized reading' (duration: 458.31698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.541332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.448632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T19:39:47.54133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:47.082696Z","time spent":"458.623196ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":13901,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-15T19:39:47.541452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.173277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-15T19:39:47.541429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.357284ms","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":"warn","ts":"2026-04-15T19:39:47.54147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.342674ms","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-15T19:39:47.541548Z","caller":"traceutil/trace.go:171","msg":"trace[934244826] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"494.940705ms","start":"2026-04-15T19:39:47.046598Z","end":"2026-04-15T19:39:47.541538Z","steps":["trace[934244826] 'process raft request' (duration: 493.509356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.541552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.880279ms","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-15T19:39:47.54158Z","caller":"traceutil/trace.go:171","msg":"trace[144734674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4800; }","duration":"293.92499ms","start":"2026-04-15T19:39:47.247649Z","end":"2026-04-15T19:39:47.541574Z","steps":["trace[144734674] 'agreement among raft nodes before linearized reading' (duration: 293.854399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.541275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.169631ms","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-15T19:39:47.541586Z","caller":"traceutil/trace.go:171","msg":"trace[1210414758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4800; }","duration":"289.439706ms","start":"2026-04-15T19:39:47.252109Z","end":"2026-04-15T19:39:47.541549Z","steps":["trace[1210414758] 'agreement among raft nodes before linearized reading' (duration: 289.251412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.541679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.001232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-15T19:39:47.541726Z","caller":"traceutil/trace.go:171","msg":"trace[1149149154] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4800; }","duration":"327.046813ms","start":"2026-04-15T19:39:47.214668Z","end":"2026-04-15T19:39:47.541715Z","steps":["trace[1149149154] 'agreement among raft nodes before linearized reading' (duration: 326.89852ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:47.541693Z","caller":"traceutil/trace.go:171","msg":"trace[2032290923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4800; }","duration":"289.603759ms","start":"2026-04-15T19:39:47.252077Z","end":"2026-04-15T19:39:47.54168Z","steps":["trace[2032290923] 'agreement among raft nodes before linearized reading' (duration: 289.052368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.541759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:47.214615Z","time spent":"327.137535ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-15T19:39:47.541374Z","caller":"traceutil/trace.go:171","msg":"trace[627663997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4800; }","duration":"749.523474ms","start":"2026-04-15T19:39:46.791839Z","end":"2026-04-15T19:39:47.541362Z","steps":["trace[627663997] 'agreement among raft nodes before linearized reading' (duration: 749.470473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.541281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.099498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-15T19:39:47.541889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:46.791817Z","time spent":"750.062375ms","remote":"127.0.0.1:41646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-15T19:39:47.541916Z","caller":"traceutil/trace.go:171","msg":"trace[706659782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4800; }","duration":"288.772462ms","start":"2026-04-15T19:39:47.253133Z","end":"2026-04-15T19:39:47.541906Z","steps":["trace[706659782] 'agreement among raft nodes before linearized reading' (duration: 288.026187ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:47.541504Z","caller":"traceutil/trace.go:171","msg":"trace[1707051063] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:4800; }","duration":"558.447236ms","start":"2026-04-15T19:39:46.983038Z","end":"2026-04-15T19:39:47.541485Z","steps":["trace[1707051063] 'agreement among raft nodes before linearized reading' (duration: 558.320044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.541679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:47.04655Z","time spent":"495.025607ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:39:47.542056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:46.982957Z","time spent":"559.086079ms","remote":"127.0.0.1:53506","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":"info","ts":"2026-04-15T19:39:47.541484Z","caller":"traceutil/trace.go:171","msg":"trace[999307914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4800; }","duration":"296.245148ms","start":"2026-04-15T19:39:47.245232Z","end":"2026-04-15T19:39:47.541477Z","steps":["trace[999307914] 'agreement among raft nodes before linearized reading' (duration: 296.164666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.542398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.082344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1bc05477-5058-4e5a-a73b-034b466ce737\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-15T19:39:47.545909Z","caller":"traceutil/trace.go:171","msg":"trace[247800232] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1bc05477-5058-4e5a-a73b-034b466ce737; range_end:; response_count:1; response_revision:4800; }","duration":"460.624548ms","start":"2026-04-15T19:39:47.085267Z","end":"2026-04-15T19:39:47.545892Z","steps":["trace[247800232] 'agreement among raft nodes before linearized reading' (duration: 456.808268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.540992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:47.19563Z","time spent":"345.233453ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:39:47.545962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:47.085258Z","time spent":"460.690739ms","remote":"127.0.0.1:41764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-1bc05477-5058-4e5a-a73b-034b466ce737\" "} {"level":"info","ts":"2026-04-15T19:39:47.755733Z","caller":"traceutil/trace.go:171","msg":"trace[292290381] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"124.581777ms","start":"2026-04-15T19:39:47.631119Z","end":"2026-04-15T19:39:47.755701Z","steps":["trace[292290381] 'process raft request' (duration: 47.81543ms)","trace[292290381] 'compare' (duration: 76.612503ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:39:47.903911Z","caller":"traceutil/trace.go:171","msg":"trace[120501107] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"271.885379ms","start":"2026-04-15T19:39:47.632004Z","end":"2026-04-15T19:39:47.90389Z","steps":["trace[120501107] 'process raft request' (duration: 271.798077ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:47.90409Z","caller":"traceutil/trace.go:171","msg":"trace[2034351074] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"271.893639ms","start":"2026-04-15T19:39:47.632031Z","end":"2026-04-15T19:39:47.903924Z","steps":["trace[2034351074] 'process raft request' (duration: 230.976693ms)","trace[2034351074] 'compare' (duration: 40.668931ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:39:47.904376Z","caller":"traceutil/trace.go:171","msg":"trace[1839596110] linearizableReadLoop","detail":"{readStateIndex:5000; appliedIndex:4996; }","duration":"270.323356ms","start":"2026-04-15T19:39:47.634042Z","end":"2026-04-15T19:39:47.904365Z","steps":["trace[1839596110] 'read index received' (duration: 44.974381ms)","trace[1839596110] 'applied index is now lower than readState.Index' (duration: 225.348055ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:39:47.904435Z","caller":"traceutil/trace.go:171","msg":"trace[1438771943] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"263.923272ms","start":"2026-04-15T19:39:47.640503Z","end":"2026-04-15T19:39:47.904426Z","steps":["trace[1438771943] 'process raft request' (duration: 263.652517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:47.904635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.581081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-04-15T19:39:47.904663Z","caller":"traceutil/trace.go:171","msg":"trace[1520870518] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4811; }","duration":"270.651563ms","start":"2026-04-15T19:39:47.634004Z","end":"2026-04-15T19:39:47.904656Z","steps":["trace[1520870518] 'agreement among raft nodes before linearized reading' (duration: 270.544031ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:47.904351Z","caller":"traceutil/trace.go:171","msg":"trace[650820172] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"272.231066ms","start":"2026-04-15T19:39:47.632094Z","end":"2026-04-15T19:39:47.904325Z","steps":["trace[650820172] 'process raft request' (duration: 271.759646ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:48.093993Z","caller":"traceutil/trace.go:171","msg":"trace[819446991] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5002; }","duration":"143.451518ms","start":"2026-04-15T19:39:47.950507Z","end":"2026-04-15T19:39:48.093959Z","steps":["trace[819446991] 'read index received' (duration: 31.0545ms)","trace[819446991] 'applied index is now lower than readState.Index' (duration: 112.395508ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:39:48.094405Z","caller":"traceutil/trace.go:171","msg":"trace[977126400] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"146.629184ms","start":"2026-04-15T19:39:47.947756Z","end":"2026-04-15T19:39:48.094385Z","steps":["trace[977126400] 'process raft request' (duration: 73.347404ms)","trace[977126400] 'compare' (duration: 72.468583ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:39:48.094502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.236117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T19:39:48.095273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.690033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-04-15T19:39:48.094618Z","caller":"traceutil/trace.go:171","msg":"trace[684963352] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4814; }","duration":"104.4002ms","start":"2026-04-15T19:39:47.990198Z","end":"2026-04-15T19:39:48.094598Z","steps":["trace[684963352] 'agreement among raft nodes before linearized reading' (duration: 104.215876ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:48.095337Z","caller":"traceutil/trace.go:171","msg":"trace[998299563] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4814; }","duration":"144.860577ms","start":"2026-04-15T19:39:47.950459Z","end":"2026-04-15T19:39:48.09532Z","steps":["trace[998299563] 'agreement among raft nodes before linearized reading' (duration: 144.631532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:48.78063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.207614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T19:39:48.780728Z","caller":"traceutil/trace.go:171","msg":"trace[1926813026] linearizableReadLoop","detail":"{readStateIndex:5005; appliedIndex:5004; }","duration":"602.6812ms","start":"2026-04-15T19:39:48.178031Z","end":"2026-04-15T19:39:48.780712Z","steps":["trace[1926813026] 'read index received' (duration: 190.259512ms)","trace[1926813026] 'applied index is now lower than readState.Index' (duration: 412.420538ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:39:48.780937Z","caller":"traceutil/trace.go:171","msg":"trace[504675006] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"679.30435ms","start":"2026-04-15T19:39:48.101619Z","end":"2026-04-15T19:39:48.780923Z","steps":["trace[504675006] 'process raft request' (duration: 266.721098ms)","trace[504675006] 'compare' (duration: 412.071851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:39:48.781009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:48.101605Z","time spent":"679.369271ms","remote":"127.0.0.1:41718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T19:39:48.781234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.191601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-15T19:39:48.781287Z","caller":"traceutil/trace.go:171","msg":"trace[241802362] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4815; }","duration":"603.287442ms","start":"2026-04-15T19:39:48.177985Z","end":"2026-04-15T19:39:48.781273Z","steps":["trace[241802362] 'agreement among raft nodes before linearized reading' (duration: 603.133519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:48.78132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:48.177965Z","time spent":"603.347514ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-15T19:39:48.781506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.778267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T19:39:48.781539Z","caller":"traceutil/trace.go:171","msg":"trace[1174752270] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:4815; }","duration":"550.842238ms","start":"2026-04-15T19:39:48.230688Z","end":"2026-04-15T19:39:48.781531Z","steps":["trace[1174752270] 'agreement among raft nodes before linearized reading' (duration: 550.758707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:48.781567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:48.230673Z","time spent":"550.887129ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":32,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-15T19:39:48.781514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.122588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T19:39:48.781984Z","caller":"traceutil/trace.go:171","msg":"trace[384293831] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4815; }","duration":"509.616227ms","start":"2026-04-15T19:39:48.272345Z","end":"2026-04-15T19:39:48.781961Z","steps":["trace[384293831] 'agreement among raft nodes before linearized reading' (duration: 509.098717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:48.782179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:48.272327Z","time spent":"509.832701ms","remote":"127.0.0.1:53630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-15T19:39:48.782239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.260341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-15T19:39:48.782287Z","caller":"traceutil/trace.go:171","msg":"trace[1766596711] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4815; }","duration":"602.339243ms","start":"2026-04-15T19:39:48.179936Z","end":"2026-04-15T19:39:48.782275Z","steps":["trace[1766596711] 'agreement among raft nodes before linearized reading' (duration: 601.207539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:48.782321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T19:39:48.17992Z","time spent":"602.394394ms","remote":"127.0.0.1:41878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-15T19:39:48.782541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.431923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:39:48.782623Z","caller":"traceutil/trace.go:171","msg":"trace[1233130014] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:4815; }","duration":"281.038426ms","start":"2026-04-15T19:39:48.501567Z","end":"2026-04-15T19:39:48.782605Z","steps":["trace[1233130014] 'agreement among raft nodes before linearized reading' (duration: 280.011014ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:49.003555Z","caller":"traceutil/trace.go:171","msg":"trace[1738189593] linearizableReadLoop","detail":"{readStateIndex:5007; appliedIndex:5005; }","duration":"212.878593ms","start":"2026-04-15T19:39:48.790653Z","end":"2026-04-15T19:39:49.003531Z","steps":["trace[1738189593] 'read index received' (duration: 117.280258ms)","trace[1738189593] 'applied index is now lower than readState.Index' (duration: 95.597225ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T19:39:49.003626Z","caller":"traceutil/trace.go:171","msg":"trace[1741238429] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"217.03533ms","start":"2026-04-15T19:39:48.786553Z","end":"2026-04-15T19:39:49.003589Z","steps":["trace[1741238429] 'process raft request' (duration: 216.928458ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:39:49.003577Z","caller":"traceutil/trace.go:171","msg":"trace[1958349599] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"218.396288ms","start":"2026-04-15T19:39:48.785152Z","end":"2026-04-15T19:39:49.003548Z","steps":["trace[1958349599] 'process raft request' (duration: 122.769492ms)","trace[1958349599] 'compare' (duration: 95.381421ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T19:39:49.003796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.593426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-15T19:39:49.003836Z","caller":"traceutil/trace.go:171","msg":"trace[612409971] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:4817; }","duration":"118.695038ms","start":"2026-04-15T19:39:48.88513Z","end":"2026-04-15T19:39:49.003825Z","steps":["trace[612409971] 'agreement among raft nodes before linearized reading' (duration: 118.539165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T19:39:49.003974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.054557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T19:39:49.004053Z","caller":"traceutil/trace.go:171","msg":"trace[974526822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4817; }","duration":"213.444385ms","start":"2026-04-15T19:39:48.790591Z","end":"2026-04-15T19:39:49.004035Z","steps":["trace[974526822] 'agreement among raft nodes before linearized reading' (duration: 213.029547ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T19:41:25.204765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2851} {"level":"info","ts":"2026-04-15T19:41:25.290809Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2851,"took":"84.293313ms","hash":1571315606,"current-db-size-bytes":53137408,"current-db-size":"53 MB","current-db-size-in-use-bytes":29220864,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-15T19:41:25.290879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1571315606,"revision":2851,"compact-revision":-1} {"level":"info","ts":"2026-04-15T19:46:25.212923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5573} {"level":"info","ts":"2026-04-15T19:46:25.306611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5573,"took":"92.492878ms","hash":2816510898,"current-db-size-bytes":53137408,"current-db-size":"53 MB","current-db-size-in-use-bytes":18251776,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-15T19:46:25.306665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2816510898,"revision":5573,"compact-revision":2851}