{"level":"warn","ts":"2026-05-15T21:17:28.709259Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T21:17:28.709398Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.202:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.202:2380","--initial-cluster=instance=https://199.19.213.202:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.202:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.202: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-05-15T21:17:28.709537Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T21:17:28.709553Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.202:2380"]} {"level":"info","ts":"2026-05-15T21:17:28.70958Z","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-05-15T21:17:28.71054Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"]} {"level":"info","ts":"2026-05-15T21:17:28.710644Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.202:2380"],"listen-peer-urls":["https://199.19.213.202:2380"],"advertise-client-urls":["https://199.19.213.202:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.202: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-05-15T21:17:28.714812Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.828555ms"} {"level":"info","ts":"2026-05-15T21:17:28.720042Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c9b4049ccf4190cf","cluster-id":"2bc66bd50b26302c"} {"level":"info","ts":"2026-05-15T21:17:28.720097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=()"} {"level":"info","ts":"2026-05-15T21:17:28.720114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became follower at term 0"} {"level":"info","ts":"2026-05-15T21:17:28.720124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c9b4049ccf4190cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-15T21:17:28.720132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became follower at term 1"} {"level":"info","ts":"2026-05-15T21:17:28.720159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=(14534246968970023119)"} {"level":"warn","ts":"2026-05-15T21:17:28.725476Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-15T21:17:28.726922Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-15T21:17:28.727961Z","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-05-15T21:17:28.730272Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c9b4049ccf4190cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-15T21:17:28.73076Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c9b4049ccf4190cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-15T21:17:28.730885Z","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-05-15T21:17:28.730945Z","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-05-15T21:17:28.730957Z","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-05-15T21:17:28.732855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=(14534246968970023119)"} {"level":"info","ts":"2026-05-15T21:17:28.733007Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bc66bd50b26302c","local-member-id":"c9b4049ccf4190cf","added-peer-id":"c9b4049ccf4190cf","added-peer-peer-urls":["https://199.19.213.202:2380"]} {"level":"info","ts":"2026-05-15T21:17:28.735202Z","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-05-15T21:17:28.73544Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c9b4049ccf4190cf","initial-advertise-peer-urls":["https://199.19.213.202:2380"],"listen-peer-urls":["https://199.19.213.202:2380"],"advertise-client-urls":["https://199.19.213.202:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-15T21:17:28.735556Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-15T21:17:28.735914Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.202:2380"} {"level":"info","ts":"2026-05-15T21:17:28.736025Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.202:2380"} {"level":"info","ts":"2026-05-15T21:17:29.620845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf is starting a new election at term 1"} {"level":"info","ts":"2026-05-15T21:17:29.620904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-15T21:17:29.620939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf received MsgPreVoteResp from c9b4049ccf4190cf at term 1"} {"level":"info","ts":"2026-05-15T21:17:29.620951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became candidate at term 2"} {"level":"info","ts":"2026-05-15T21:17:29.620957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf received MsgVoteResp from c9b4049ccf4190cf at term 2"} {"level":"info","ts":"2026-05-15T21:17:29.620966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became leader at term 2"} {"level":"info","ts":"2026-05-15T21:17:29.620972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c9b4049ccf4190cf elected leader c9b4049ccf4190cf at term 2"} {"level":"info","ts":"2026-05-15T21:17:29.62312Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T21:17:29.626411Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c9b4049ccf4190cf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.202:2379]}","request-path":"/0/members/c9b4049ccf4190cf/attributes","cluster-id":"2bc66bd50b26302c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-15T21:17:29.626424Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T21:17:29.627294Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T21:17:29.627953Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-15T21:17:29.62804Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-15T21:17:29.628997Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bc66bd50b26302c","local-member-id":"c9b4049ccf4190cf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T21:17:29.629081Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T21:17:29.629106Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T21:17:29.6292Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.202:2379"} {"level":"info","ts":"2026-05-15T21:17:29.629534Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-15T21:18:01.80941Z","caller":"traceutil/trace.go:171","msg":"trace[176140453] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"171.135904ms","start":"2026-05-15T21:18:01.638245Z","end":"2026-05-15T21:18:01.809381Z","steps":["trace[176140453] 'process raft request' (duration: 170.95868ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:01.82297Z","caller":"traceutil/trace.go:171","msg":"trace[679802609] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"180.610915ms","start":"2026-05-15T21:18:01.642259Z","end":"2026-05-15T21:18:01.82287Z","steps":["trace[679802609] 'process raft request' (duration: 180.434011ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:01.823179Z","caller":"traceutil/trace.go:171","msg":"trace[1510539590] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"161.169024ms","start":"2026-05-15T21:18:01.661995Z","end":"2026-05-15T21:18:01.823164Z","steps":["trace[1510539590] 'process raft request' (duration: 160.950727ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:01.823252Z","caller":"traceutil/trace.go:171","msg":"trace[1280534843] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"159.446737ms","start":"2026-05-15T21:18:01.663793Z","end":"2026-05-15T21:18:01.82324Z","steps":["trace[1280534843] 'process raft request' (duration: 159.22575ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:01.823209Z","caller":"traceutil/trace.go:171","msg":"trace[1408303819] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"161.267062ms","start":"2026-05-15T21:18:01.661902Z","end":"2026-05-15T21:18:01.823169Z","steps":["trace[1408303819] 'process raft request' (duration: 160.968078ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:01.823321Z","caller":"traceutil/trace.go:171","msg":"trace[1898307163] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"153.998906ms","start":"2026-05-15T21:18:01.669308Z","end":"2026-05-15T21:18:01.823307Z","steps":["trace[1898307163] 'process raft request' (duration: 153.809421ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:01.823328Z","caller":"traceutil/trace.go:171","msg":"trace[1145088740] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"179.461593ms","start":"2026-05-15T21:18:01.643701Z","end":"2026-05-15T21:18:01.823162Z","steps":["trace[1145088740] 'process raft request' (duration: 179.084273ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:01.823409Z","caller":"traceutil/trace.go:171","msg":"trace[336798767] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"148.729028ms","start":"2026-05-15T21:18:01.674672Z","end":"2026-05-15T21:18:01.823401Z","steps":["trace[336798767] 'process raft request' (duration: 148.490519ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:01.823444Z","caller":"traceutil/trace.go:171","msg":"trace[804294969] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"133.047706ms","start":"2026-05-15T21:18:01.690389Z","end":"2026-05-15T21:18:01.823437Z","steps":["trace[804294969] 'process raft request' (duration: 132.876242ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:01.823411Z","caller":"traceutil/trace.go:171","msg":"trace[1521496120] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"122.392471ms","start":"2026-05-15T21:18:01.701006Z","end":"2026-05-15T21:18:01.823399Z","steps":["trace[1521496120] 'process raft request' (duration: 122.348227ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:01.823105Z","caller":"traceutil/trace.go:171","msg":"trace[16538648] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"173.554565ms","start":"2026-05-15T21:18:01.649529Z","end":"2026-05-15T21:18:01.823084Z","steps":["trace[16538648] 'process raft request' (duration: 173.298995ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:08.170895Z","caller":"traceutil/trace.go:171","msg":"trace[1441298386] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"137.194665ms","start":"2026-05-15T21:18:08.03368Z","end":"2026-05-15T21:18:08.170875Z","steps":["trace[1441298386] 'process raft request' (duration: 136.782572ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:08.633876Z","caller":"traceutil/trace.go:171","msg":"trace[142924357] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"374.169527ms","start":"2026-05-15T21:18:08.259689Z","end":"2026-05-15T21:18:08.633859Z","steps":["trace[142924357] 'process raft request' (duration: 373.95661ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:08.633893Z","caller":"traceutil/trace.go:171","msg":"trace[939623176] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:649; }","duration":"360.069151ms","start":"2026-05-15T21:18:08.273796Z","end":"2026-05-15T21:18:08.633865Z","steps":["trace[939623176] 'read index received' (duration: 360.05786ms)","trace[939623176] 'applied index is now lower than readState.Index' (duration: 9.371µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T21:18:08.634056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.248065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.634097Z","caller":"traceutil/trace.go:171","msg":"trace[2126469318] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:628; }","duration":"360.356634ms","start":"2026-05-15T21:18:08.273734Z","end":"2026-05-15T21:18:08.63409Z","steps":["trace[2126469318] 'agreement among raft nodes before linearized reading' (duration: 360.242235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.634119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.273712Z","time spent":"360.401337ms","remote":"127.0.0.1:44272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-05-15T21:18:08.634376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.259673Z","time spent":"374.271245ms","remote":"127.0.0.1:49522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T21:18:08.74524Z","caller":"traceutil/trace.go:171","msg":"trace[480993532] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"111.249013ms","start":"2026-05-15T21:18:08.633954Z","end":"2026-05-15T21:18:08.745203Z","steps":["trace[480993532] 'read index received' (duration: 66.397206ms)","trace[480993532] 'applied index is now lower than readState.Index' (duration: 44.848637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T21:18:08.745421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.461064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.745446Z","caller":"traceutil/trace.go:171","msg":"trace[1354112794] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:632; }","duration":"241.542721ms","start":"2026-05-15T21:18:08.503896Z","end":"2026-05-15T21:18:08.745438Z","steps":["trace[1354112794] 'agreement among raft nodes before linearized reading' (duration: 241.444363ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:08.745429Z","caller":"traceutil/trace.go:171","msg":"trace[105403370] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"105.737078ms","start":"2026-05-15T21:18:08.63967Z","end":"2026-05-15T21:18:08.745407Z","steps":["trace[105403370] 'process raft request' (duration: 105.617459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.745657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.747267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.745679Z","caller":"traceutil/trace.go:171","msg":"trace[429682389] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:632; }","duration":"241.772049ms","start":"2026-05-15T21:18:08.503901Z","end":"2026-05-15T21:18:08.745673Z","steps":["trace[429682389] 'agreement among raft nodes before linearized reading' (duration: 241.735626ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:08.74568Z","caller":"traceutil/trace.go:171","msg":"trace[1847017696] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"107.281572ms","start":"2026-05-15T21:18:08.638387Z","end":"2026-05-15T21:18:08.745668Z","steps":["trace[1847017696] 'process raft request' (duration: 106.672173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.745759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.679434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.745763Z","caller":"traceutil/trace.go:171","msg":"trace[2054262064] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"106.175584ms","start":"2026-05-15T21:18:08.639574Z","end":"2026-05-15T21:18:08.745749Z","steps":["trace[2054262064] 'process raft request' (duration: 105.663373ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:08.745776Z","caller":"traceutil/trace.go:171","msg":"trace[851155144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"351.717497ms","start":"2026-05-15T21:18:08.394054Z","end":"2026-05-15T21:18:08.745771Z","steps":["trace[851155144] 'agreement among raft nodes before linearized reading' (duration: 351.690604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.745797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.394043Z","time spent":"351.749889ms","remote":"127.0.0.1:49332","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-05-15T21:18:08.745906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.613197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.745924Z","caller":"traceutil/trace.go:171","msg":"trace[1195542354] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"107.161272ms","start":"2026-05-15T21:18:08.638755Z","end":"2026-05-15T21:18:08.745916Z","steps":["trace[1195542354] 'process raft request' (duration: 106.437184ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:08.745926Z","caller":"traceutil/trace.go:171","msg":"trace[595794209] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:632; }","duration":"381.658341ms","start":"2026-05-15T21:18:08.36426Z","end":"2026-05-15T21:18:08.745919Z","steps":["trace[595794209] 'agreement among raft nodes before linearized reading' (duration: 381.626499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.745943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.364247Z","time spent":"381.691954ms","remote":"127.0.0.1:44296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-15T21:18:08.746035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.38796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.746052Z","caller":"traceutil/trace.go:171","msg":"trace[33360570] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:632; }","duration":"382.416063ms","start":"2026-05-15T21:18:08.36363Z","end":"2026-05-15T21:18:08.746046Z","steps":["trace[33360570] 'agreement among raft nodes before linearized reading' (duration: 382.38801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.746069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.363625Z","time spent":"382.438865ms","remote":"127.0.0.1:44296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-15T21:18:08.746142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.652692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.746161Z","caller":"traceutil/trace.go:171","msg":"trace[284341908] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:632; }","duration":"382.704826ms","start":"2026-05-15T21:18:08.363451Z","end":"2026-05-15T21:18:08.746156Z","steps":["trace[284341908] 'agreement among raft nodes before linearized reading' (duration: 382.675344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.746178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.363428Z","time spent":"382.745149ms","remote":"127.0.0.1:44296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-15T21:18:08.746252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.612586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T21:18:08.746251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.402431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.746274Z","caller":"traceutil/trace.go:171","msg":"trace[81001185] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:632; }","duration":"456.673621ms","start":"2026-05-15T21:18:08.289592Z","end":"2026-05-15T21:18:08.746266Z","steps":["trace[81001185] 'agreement among raft nodes before linearized reading' (duration: 456.632358ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:18:08.746292Z","caller":"traceutil/trace.go:171","msg":"trace[250089615] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:632; }","duration":"472.486247ms","start":"2026-05-15T21:18:08.273794Z","end":"2026-05-15T21:18:08.74628Z","steps":["trace[250089615] 'agreement among raft nodes before linearized reading' (duration: 472.410201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.746315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.289573Z","time spent":"456.734547ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-15T21:18:08.746326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.273775Z","time spent":"472.542541ms","remote":"127.0.0.1:44272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-15T21:18:08.746489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.370435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.74652Z","caller":"traceutil/trace.go:171","msg":"trace[1449325701] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:632; }","duration":"408.41952ms","start":"2026-05-15T21:18:08.338094Z","end":"2026-05-15T21:18:08.746513Z","steps":["trace[1449325701] 'agreement among raft nodes before linearized reading' (duration: 408.374687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.746552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.338081Z","time spent":"408.465694ms","remote":"127.0.0.1:44286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-05-15T21:18:08.74667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.976874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.746712Z","caller":"traceutil/trace.go:171","msg":"trace[1398250969] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:632; }","duration":"409.063701ms","start":"2026-05-15T21:18:08.337641Z","end":"2026-05-15T21:18:08.746704Z","steps":["trace[1398250969] 'agreement among raft nodes before linearized reading' (duration: 409.012787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.746734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.337618Z","time spent":"409.108035ms","remote":"127.0.0.1:44286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-15T21:18:08.746834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.336833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.746869Z","caller":"traceutil/trace.go:171","msg":"trace[375948323] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:632; }","duration":"409.389228ms","start":"2026-05-15T21:18:08.337474Z","end":"2026-05-15T21:18:08.746864Z","steps":["trace[375948323] 'agreement among raft nodes before linearized reading' (duration: 409.340684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.746888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.337463Z","time spent":"409.41902ms","remote":"127.0.0.1:44286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-15T21:18:08.747211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.650979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.747252Z","caller":"traceutil/trace.go:171","msg":"trace[419405820] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:632; }","duration":"457.701794ms","start":"2026-05-15T21:18:08.289534Z","end":"2026-05-15T21:18:08.747236Z","steps":["trace[419405820] 'agreement among raft nodes before linearized reading' (duration: 457.658961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.74728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.289526Z","time spent":"457.745887ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-15T21:18:08.747325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.143649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.747347Z","caller":"traceutil/trace.go:171","msg":"trace[142079958] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:632; }","duration":"473.190092ms","start":"2026-05-15T21:18:08.27415Z","end":"2026-05-15T21:18:08.74734Z","steps":["trace[142079958] 'agreement among raft nodes before linearized reading' (duration: 473.15565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.747371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.274139Z","time spent":"473.227086ms","remote":"127.0.0.1:44272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-05-15T21:18:08.747394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.789921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:18:08.747423Z","caller":"traceutil/trace.go:171","msg":"trace[279278690] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:632; }","duration":"457.824273ms","start":"2026-05-15T21:18:08.289589Z","end":"2026-05-15T21:18:08.747413Z","steps":["trace[279278690] 'agreement among raft nodes before linearized reading' (duration: 457.78626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:18:08.747444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:18:08.289579Z","time spent":"457.858786ms","remote":"127.0.0.1:44284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-15T21:19:42.656795Z","caller":"traceutil/trace.go:171","msg":"trace[327590239] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"331.277494ms","start":"2026-05-15T21:19:42.325487Z","end":"2026-05-15T21:19:42.656764Z","steps":["trace[327590239] 'read index received' (duration: 244.068767ms)","trace[327590239] 'applied index is now lower than readState.Index' (duration: 87.207727ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:19:42.656923Z","caller":"traceutil/trace.go:171","msg":"trace[2122357005] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"392.980617ms","start":"2026-05-15T21:19:42.263928Z","end":"2026-05-15T21:19:42.656908Z","steps":["trace[2122357005] 'process raft request' (duration: 305.603996ms)","trace[2122357005] 'compare' (duration: 87.112314ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T21:19:42.657244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:19:42.263888Z","time spent":"393.062619ms","remote":"127.0.0.1:49584","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-05-15T21:19:42.657279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.782339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2cd627a-6b1d-48dd-9b87-eba21ae43e46\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-15T21:19:42.657335Z","caller":"traceutil/trace.go:171","msg":"trace[1482212366] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c2cd627a-6b1d-48dd-9b87-eba21ae43e46; range_end:; response_count:1; response_revision:1871; }","duration":"331.872821ms","start":"2026-05-15T21:19:42.325446Z","end":"2026-05-15T21:19:42.657319Z","steps":["trace[1482212366] 'agreement among raft nodes before linearized reading' (duration: 331.663935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:19:42.657391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:19:42.325429Z","time spent":"331.950273ms","remote":"127.0.0.1:49522","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-c2cd627a-6b1d-48dd-9b87-eba21ae43e46\" "} {"level":"warn","ts":"2026-05-15T21:19:42.692051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.423998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:19:42.692107Z","caller":"traceutil/trace.go:171","msg":"trace[2004857819] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"351.076651ms","start":"2026-05-15T21:19:42.340936Z","end":"2026-05-15T21:19:42.692012Z","steps":["trace[2004857819] 'process raft request' (duration: 350.896086ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:19:42.692168Z","caller":"traceutil/trace.go:171","msg":"trace[2142684618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1872; }","duration":"298.587133ms","start":"2026-05-15T21:19:42.393565Z","end":"2026-05-15T21:19:42.692152Z","steps":["trace[2142684618] 'agreement among raft nodes before linearized reading' (duration: 298.417008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:19:42.692299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:19:42.340902Z","time spent":"351.295527ms","remote":"127.0.0.1:49584","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-05-15T21:19:42.692404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.389776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T21:19:42.692425Z","caller":"traceutil/trace.go:171","msg":"trace[1777152250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1872; }","duration":"161.436157ms","start":"2026-05-15T21:19:42.530983Z","end":"2026-05-15T21:19:42.692419Z","steps":["trace[1777152250] 'agreement among raft nodes before linearized reading' (duration: 161.345405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:19:42.693199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.136153ms","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-05-15T21:19:42.693257Z","caller":"traceutil/trace.go:171","msg":"trace[1561553316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1872; }","duration":"234.227156ms","start":"2026-05-15T21:19:42.459017Z","end":"2026-05-15T21:19:42.693244Z","steps":["trace[1561553316] 'agreement among raft nodes before linearized reading' (duration: 233.506076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:20:17.168882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.086341ms","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-05-15T21:20:17.16897Z","caller":"traceutil/trace.go:171","msg":"trace[2008546592] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2113; }","duration":"171.238188ms","start":"2026-05-15T21:20:16.997711Z","end":"2026-05-15T21:20:17.16895Z","steps":["trace[2008546592] 'range keys from in-memory index tree' (duration: 170.896392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:20:17.879822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.237666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T21:20:17.88008Z","caller":"traceutil/trace.go:171","msg":"trace[1429699232] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"206.294ms","start":"2026-05-15T21:20:17.673732Z","end":"2026-05-15T21:20:17.880026Z","steps":["trace[1429699232] 'process raft request' (duration: 15.526159ms)","trace[1429699232] 'compare' (duration: 190.023476ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T21:20:18.663731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.248227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T21:20:18.664079Z","caller":"traceutil/trace.go:171","msg":"trace[1511699628] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"301.310263ms","start":"2026-05-15T21:20:18.362685Z","end":"2026-05-15T21:20:18.663995Z","steps":["trace[1511699628] 'process raft request' (duration: 117.671858ms)","trace[1511699628] 'compare' (duration: 183.053257ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T21:20:18.66434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.028003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:20:18.66441Z","caller":"traceutil/trace.go:171","msg":"trace[246768469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2119; }","duration":"270.148709ms","start":"2026-05-15T21:20:18.394246Z","end":"2026-05-15T21:20:18.664395Z","steps":["trace[246768469] 'agreement among raft nodes before linearized reading' (duration: 269.95377ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:20:18.664115Z","caller":"traceutil/trace.go:171","msg":"trace[2092537169] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"269.806403ms","start":"2026-05-15T21:20:18.394282Z","end":"2026-05-15T21:20:18.664089Z","steps":["trace[2092537169] 'read index received' (duration: 86.093505ms)","trace[2092537169] 'applied index is now lower than readState.Index' (duration: 183.700168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T21:20:18.664836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:20:18.362653Z","time spent":"301.734703ms","remote":"127.0.0.1:49584","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-05-15T21:20:18.664866Z","caller":"traceutil/trace.go:171","msg":"trace[283621534] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"221.781251ms","start":"2026-05-15T21:20:18.443048Z","end":"2026-05-15T21:20:18.664829Z","steps":["trace[283621534] 'process raft request' (duration: 221.344421ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:20:18.989583Z","caller":"traceutil/trace.go:171","msg":"trace[730665711] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"179.274095ms","start":"2026-05-15T21:20:18.810283Z","end":"2026-05-15T21:20:18.989557Z","steps":["trace[730665711] 'process raft request' (duration: 176.771849ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:20:19.040708Z","caller":"traceutil/trace.go:171","msg":"trace[575326692] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"202.230956ms","start":"2026-05-15T21:20:18.838442Z","end":"2026-05-15T21:20:19.040673Z","steps":["trace[575326692] 'process raft request' (duration: 201.834858ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:20:19.214557Z","caller":"traceutil/trace.go:171","msg":"trace[658597782] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"169.653245ms","start":"2026-05-15T21:20:19.044877Z","end":"2026-05-15T21:20:19.21453Z","steps":["trace[658597782] 'process raft request' (duration: 86.559047ms)","trace[658597782] 'compare' (duration: 82.880398ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:20:19.214695Z","caller":"traceutil/trace.go:171","msg":"trace[320979317] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"169.623633ms","start":"2026-05-15T21:20:19.045065Z","end":"2026-05-15T21:20:19.214688Z","steps":["trace[320979317] 'process raft request' (duration: 169.378992ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:21:28.596293Z","caller":"traceutil/trace.go:171","msg":"trace[228612585] linearizableReadLoop","detail":"{readStateIndex:2690; appliedIndex:2689; }","duration":"203.533006ms","start":"2026-05-15T21:21:28.392743Z","end":"2026-05-15T21:21:28.596276Z","steps":["trace[228612585] 'read index received' (duration: 203.225327ms)","trace[228612585] 'applied index is now lower than readState.Index' (duration: 306.969µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:21:28.596378Z","caller":"traceutil/trace.go:171","msg":"trace[1878324772] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"228.005728ms","start":"2026-05-15T21:21:28.368367Z","end":"2026-05-15T21:21:28.596373Z","steps":["trace[1878324772] 'process raft request' (duration: 227.612694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:21:28.596517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.257687ms","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-05-15T21:21:28.596539Z","caller":"traceutil/trace.go:171","msg":"trace[1124791768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2596; }","duration":"110.322471ms","start":"2026-05-15T21:21:28.486211Z","end":"2026-05-15T21:21:28.596534Z","steps":["trace[1124791768] 'agreement among raft nodes before linearized reading' (duration: 110.239016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:21:28.59661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.860087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:21:28.59666Z","caller":"traceutil/trace.go:171","msg":"trace[1578764922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2596; }","duration":"203.932071ms","start":"2026-05-15T21:21:28.392716Z","end":"2026-05-15T21:21:28.596648Z","steps":["trace[1578764922] 'agreement among raft nodes before linearized reading' (duration: 203.860166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:21:28.596628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.335775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-15T21:21:28.596738Z","caller":"traceutil/trace.go:171","msg":"trace[414631014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2596; }","duration":"194.475243ms","start":"2026-05-15T21:21:28.402242Z","end":"2026-05-15T21:21:28.596717Z","steps":["trace[414631014] 'agreement among raft nodes before linearized reading' (duration: 194.176115ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:22:00.868334Z","caller":"traceutil/trace.go:171","msg":"trace[902594302] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"106.717665ms","start":"2026-05-15T21:22:00.761586Z","end":"2026-05-15T21:22:00.868304Z","steps":["trace[902594302] 'process raft request' (duration: 106.518402ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:22:00.890198Z","caller":"traceutil/trace.go:171","msg":"trace[59053399] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"127.968834ms","start":"2026-05-15T21:22:00.762193Z","end":"2026-05-15T21:22:00.890161Z","steps":["trace[59053399] 'process raft request' (duration: 127.833916ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:22:01.36846Z","caller":"traceutil/trace.go:171","msg":"trace[988707769] linearizableReadLoop","detail":"{readStateIndex:2877; appliedIndex:2876; }","duration":"254.961784ms","start":"2026-05-15T21:22:01.113477Z","end":"2026-05-15T21:22:01.368438Z","steps":["trace[988707769] 'read index received' (duration: 254.755081ms)","trace[988707769] 'applied index is now lower than readState.Index' (duration: 205.973µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:22:01.368738Z","caller":"traceutil/trace.go:171","msg":"trace[1070666659] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"316.137678ms","start":"2026-05-15T21:22:01.052591Z","end":"2026-05-15T21:22:01.368729Z","steps":["trace[1070666659] 'process raft request' (duration: 315.704949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:22:01.368828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:22:01.052572Z","time spent":"316.189371ms","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T21:22:01.368927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.425295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-15T21:22:01.368972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.556975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18afda06f4c3d277\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-05-15T21:22:01.369049Z","caller":"traceutil/trace.go:171","msg":"trace[1620122631] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18afda06f4c3d277; range_end:; response_count:1; response_revision:2776; }","duration":"121.62493ms","start":"2026-05-15T21:22:01.247386Z","end":"2026-05-15T21:22:01.369011Z","steps":["trace[1620122631] 'agreement among raft nodes before linearized reading' (duration: 121.413707ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:22:01.369055Z","caller":"traceutil/trace.go:171","msg":"trace[2024534620] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2776; }","duration":"255.571155ms","start":"2026-05-15T21:22:01.113426Z","end":"2026-05-15T21:22:01.368997Z","steps":["trace[2024534620] 'agreement among raft nodes before linearized reading' (duration: 255.396714ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:22:01.629421Z","caller":"traceutil/trace.go:171","msg":"trace[444911505] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"256.849728ms","start":"2026-05-15T21:22:01.372538Z","end":"2026-05-15T21:22:01.629387Z","steps":["trace[444911505] 'process raft request' (duration: 174.611235ms)","trace[444911505] 'compare' (duration: 82.072382ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:22:01.675139Z","caller":"traceutil/trace.go:171","msg":"trace[1117188347] linearizableReadLoop","detail":"{readStateIndex:2879; appliedIndex:2877; }","duration":"281.239563ms","start":"2026-05-15T21:22:01.393874Z","end":"2026-05-15T21:22:01.675114Z","steps":["trace[1117188347] 'read index received' (duration: 153.28807ms)","trace[1117188347] 'applied index is now lower than readState.Index' (duration: 127.950463ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:22:01.675167Z","caller":"traceutil/trace.go:171","msg":"trace[694558331] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"278.960485ms","start":"2026-05-15T21:22:01.396176Z","end":"2026-05-15T21:22:01.675136Z","steps":["trace[694558331] 'process raft request' (duration: 278.801494ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:22:01.675199Z","caller":"traceutil/trace.go:171","msg":"trace[1159716196] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"302.555248ms","start":"2026-05-15T21:22:01.372581Z","end":"2026-05-15T21:22:01.675137Z","steps":["trace[1159716196] 'process raft request' (duration: 302.244908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:22:01.675307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.413804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:22:01.675477Z","caller":"traceutil/trace.go:171","msg":"trace[1285472452] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"273.260641ms","start":"2026-05-15T21:22:01.402161Z","end":"2026-05-15T21:22:01.675422Z","steps":["trace[1285472452] 'process raft request' (duration: 272.910429ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:22:01.675395Z","caller":"traceutil/trace.go:171","msg":"trace[800418157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2780; }","duration":"281.561054ms","start":"2026-05-15T21:22:01.393821Z","end":"2026-05-15T21:22:01.675382Z","steps":["trace[800418157] 'agreement among raft nodes before linearized reading' (duration: 281.382352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:22:01.67561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:22:01.372562Z","time spent":"302.772823ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T21:25:47.171436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.188293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-15T21:25:47.171958Z","caller":"traceutil/trace.go:171","msg":"trace[418749467] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4586; }","duration":"218.797656ms","start":"2026-05-15T21:25:46.953146Z","end":"2026-05-15T21:25:47.171944Z","steps":["trace[418749467] 'compare' (duration: 198.628833ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:25:47.172175Z","caller":"traceutil/trace.go:171","msg":"trace[1443856856] transaction","detail":"{read_only:false; response_revision:4587; number_of_response:1; }","duration":"218.540657ms","start":"2026-05-15T21:25:46.953613Z","end":"2026-05-15T21:25:47.172154Z","steps":["trace[1443856856] 'process raft request' (duration: 217.928244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:25:47.172614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.643925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T21:25:47.172369Z","caller":"traceutil/trace.go:171","msg":"trace[184448919] linearizableReadLoop","detail":"{readStateIndex:4759; appliedIndex:4757; }","duration":"203.663869ms","start":"2026-05-15T21:25:46.967962Z","end":"2026-05-15T21:25:47.171626Z","steps":["trace[184448919] 'read index received' (duration: 4.132833ms)","trace[184448919] 'applied index is now lower than readState.Index' (duration: 199.529986ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:25:47.172657Z","caller":"traceutil/trace.go:171","msg":"trace[1374542285] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4587; }","duration":"204.737138ms","start":"2026-05-15T21:25:46.967909Z","end":"2026-05-15T21:25:47.172646Z","steps":["trace[1374542285] 'agreement among raft nodes before linearized reading' (duration: 204.486359ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:25:56.388133Z","caller":"traceutil/trace.go:171","msg":"trace[2081830362] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"163.902158ms","start":"2026-05-15T21:25:56.224208Z","end":"2026-05-15T21:25:56.38811Z","steps":["trace[2081830362] 'process raft request' (duration: 163.753882ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:25:56.570398Z","caller":"traceutil/trace.go:171","msg":"trace[417842462] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"157.300622ms","start":"2026-05-15T21:25:56.413056Z","end":"2026-05-15T21:25:56.570357Z","steps":["trace[417842462] 'process raft request' (duration: 125.83158ms)","trace[417842462] 'compare' (duration: 31.13728ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:25:56.584847Z","caller":"traceutil/trace.go:171","msg":"trace[20073948] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"171.107195ms","start":"2026-05-15T21:25:56.413704Z","end":"2026-05-15T21:25:56.584811Z","steps":["trace[20073948] 'process raft request' (duration: 170.818564ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:25:56.939337Z","caller":"traceutil/trace.go:171","msg":"trace[624050833] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"180.085482ms","start":"2026-05-15T21:25:56.759224Z","end":"2026-05-15T21:25:56.939309Z","steps":["trace[624050833] 'process raft request' (duration: 179.910815ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:25:57.198764Z","caller":"traceutil/trace.go:171","msg":"trace[792921136] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"134.339089ms","start":"2026-05-15T21:25:57.064391Z","end":"2026-05-15T21:25:57.19873Z","steps":["trace[792921136] 'process raft request' (duration: 128.852247ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:25:57.199067Z","caller":"traceutil/trace.go:171","msg":"trace[735731710] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"123.622655ms","start":"2026-05-15T21:25:57.075383Z","end":"2026-05-15T21:25:57.199006Z","steps":["trace[735731710] 'process raft request' (duration: 123.277582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:25:57.199941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.050257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7d977c72-3096-4c30-8ea2-0595997fa784\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-15T21:25:57.200087Z","caller":"traceutil/trace.go:171","msg":"trace[128960926] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7d977c72-3096-4c30-8ea2-0595997fa784; range_end:; response_count:1; response_revision:4740; }","duration":"134.934363ms","start":"2026-05-15T21:25:57.06513Z","end":"2026-05-15T21:25:57.200064Z","steps":["trace[128960926] 'agreement among raft nodes before linearized reading' (duration: 133.963074ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:25:57.199036Z","caller":"traceutil/trace.go:171","msg":"trace[233839437] linearizableReadLoop","detail":"{readStateIndex:4916; appliedIndex:4915; }","duration":"133.832619ms","start":"2026-05-15T21:25:57.065146Z","end":"2026-05-15T21:25:57.198978Z","steps":["trace[233839437] 'read index received' (duration: 32.714345ms)","trace[233839437] 'applied index is now lower than readState.Index' (duration: 101.116984ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:25:57.206826Z","caller":"traceutil/trace.go:171","msg":"trace[228620503] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"132.062112ms","start":"2026-05-15T21:25:57.066749Z","end":"2026-05-15T21:25:57.198811Z","steps":["trace[228620503] 'process raft request' (duration: 131.851393ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:26:00.194714Z","caller":"traceutil/trace.go:171","msg":"trace[1300300506] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"135.469818ms","start":"2026-05-15T21:26:00.059224Z","end":"2026-05-15T21:26:00.194694Z","steps":["trace[1300300506] 'process raft request' (duration: 135.369804ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:27:29.673952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2925} {"level":"info","ts":"2026-05-15T21:27:29.762153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2925,"took":"84.857431ms","hash":799113683,"current-db-size-bytes":53067776,"current-db-size":"53 MB","current-db-size-in-use-bytes":29007872,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-15T21:27:29.762313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":799113683,"revision":2925,"compact-revision":-1} {"level":"info","ts":"2026-05-15T21:28:44.928647Z","caller":"traceutil/trace.go:171","msg":"trace[294110109] linearizableReadLoop","detail":"{readStateIndex:6248; appliedIndex:6247; }","duration":"293.994301ms","start":"2026-05-15T21:28:44.634618Z","end":"2026-05-15T21:28:44.928612Z","steps":["trace[294110109] 'read index received' (duration: 293.79667ms)","trace[294110109] 'applied index is now lower than readState.Index' (duration: 196.291µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:28:44.929254Z","caller":"traceutil/trace.go:171","msg":"trace[703956458] transaction","detail":"{read_only:false; response_revision:6031; number_of_response:1; }","duration":"477.481115ms","start":"2026-05-15T21:28:44.45132Z","end":"2026-05-15T21:28:44.928801Z","steps":["trace[703956458] 'process raft request' (duration: 477.113005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:44.929286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.643005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-05-15T21:28:44.929681Z","caller":"traceutil/trace.go:171","msg":"trace[1432133487] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:6031; }","duration":"295.097159ms","start":"2026-05-15T21:28:44.634562Z","end":"2026-05-15T21:28:44.929659Z","steps":["trace[1432133487] 'agreement among raft nodes before linearized reading' (duration: 294.189021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:44.930136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:44.451303Z","time spent":"478.110888ms","remote":"127.0.0.1:49584","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-05-15T21:28:52.555772Z","caller":"traceutil/trace.go:171","msg":"trace[1063121247] linearizableReadLoop","detail":"{readStateIndex:6293; appliedIndex:6292; }","duration":"426.540671ms","start":"2026-05-15T21:28:52.129197Z","end":"2026-05-15T21:28:52.555737Z","steps":["trace[1063121247] 'read index received' (duration: 426.3635ms)","trace[1063121247] 'applied index is now lower than readState.Index' (duration: 175.841µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:28:52.555845Z","caller":"traceutil/trace.go:171","msg":"trace[83612299] transaction","detail":"{read_only:false; response_revision:6074; number_of_response:1; }","duration":"484.800919ms","start":"2026-05-15T21:28:52.071022Z","end":"2026-05-15T21:28:52.555822Z","steps":["trace[83612299] 'process raft request' (duration: 484.534034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:52.555972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:52.071005Z","time spent":"484.886704ms","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T21:28:52.555973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.763832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T21:28:52.556029Z","caller":"traceutil/trace.go:171","msg":"trace[1132878595] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6074; }","duration":"426.835127ms","start":"2026-05-15T21:28:52.129179Z","end":"2026-05-15T21:28:52.556014Z","steps":["trace[1132878595] 'agreement among raft nodes before linearized reading' (duration: 426.731191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:52.556084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:52.129104Z","time spent":"426.966094ms","remote":"127.0.0.1:34316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-15T21:28:52.556741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.109644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T21:28:52.556832Z","caller":"traceutil/trace.go:171","msg":"trace[233891969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6075; }","duration":"348.262052ms","start":"2026-05-15T21:28:52.20855Z","end":"2026-05-15T21:28:52.556812Z","steps":["trace[233891969] 'agreement among raft nodes before linearized reading' (duration: 347.984848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:52.556877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:52.20852Z","time spent":"348.348857ms","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-15T21:28:53.752166Z","caller":"traceutil/trace.go:171","msg":"trace[1029623603] transaction","detail":"{read_only:false; response_revision:6083; number_of_response:1; }","duration":"170.668272ms","start":"2026-05-15T21:28:53.581471Z","end":"2026-05-15T21:28:53.752139Z","steps":["trace[1029623603] 'process raft request' (duration: 170.520304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:56.629134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732779905304245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T21:28:57.158371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434732779905304245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-15T21:28:57.622947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.230543206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-15T21:28:57.623023Z","caller":"traceutil/trace.go:171","msg":"trace[1332892041] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.230665852s","start":"2026-05-15T21:28:55.392343Z","end":"2026-05-15T21:28:57.623009Z","steps":["trace[1332892041] 'agreement among raft nodes before linearized reading' (duration: 2.230574688s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:57.623053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:55.39233Z","time spent":"2.230716185s","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/15 21:28:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-15T21:28:57.653478Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.343975874s","expected-duration":"1s"} {"level":"info","ts":"2026-05-15T21:28:57.658184Z","caller":"traceutil/trace.go:171","msg":"trace[657215534] transaction","detail":"{read_only:false; response_revision:6089; number_of_response:1; }","duration":"2.35021375s","start":"2026-05-15T21:28:55.307944Z","end":"2026-05-15T21:28:57.658158Z","steps":["trace[657215534] 'process raft request' (duration: 2.347549046s)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:28:57.658215Z","caller":"traceutil/trace.go:171","msg":"trace[1095286384] transaction","detail":"{read_only:false; response_revision:6090; number_of_response:1; }","duration":"2.35019491s","start":"2026-05-15T21:28:55.30799Z","end":"2026-05-15T21:28:57.658185Z","steps":["trace[1095286384] 'process raft request' (duration: 2.350048232s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:57.658319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:55.307936Z","time spent":"2.350317707s","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T21:28:57.658357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:55.307983Z","time spent":"2.350305545s","remote":"127.0.0.1:49584","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-05-15T21:28:57.658417Z","caller":"traceutil/trace.go:171","msg":"trace[1757001416] transaction","detail":"{read_only:false; response_revision:6091; number_of_response:1; }","duration":"2.3503892s","start":"2026-05-15T21:28:55.308Z","end":"2026-05-15T21:28:57.658389Z","steps":["trace[1757001416] 'process raft request' (duration: 2.350149597s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:57.658542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:55.307988Z","time spent":"2.350494306s","remote":"127.0.0.1:49584","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-05-15T21:28:57.658713Z","caller":"traceutil/trace.go:171","msg":"trace[989477936] transaction","detail":"{read_only:false; response_revision:6092; number_of_response:1; }","duration":"2.324912544s","start":"2026-05-15T21:28:55.333794Z","end":"2026-05-15T21:28:57.658706Z","steps":["trace[989477936] 'process raft request' (duration: 2.324520513s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:57.658748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:55.333772Z","time spent":"2.324957667s","remote":"127.0.0.1:49584","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-05-15T21:28:57.658935Z","caller":"traceutil/trace.go:171","msg":"trace[875879717] linearizableReadLoop","detail":"{readStateIndex:6314; appliedIndex:6308; }","duration":"2.266542373s","start":"2026-05-15T21:28:55.392383Z","end":"2026-05-15T21:28:57.658925Z","steps":["trace[875879717] 'read index received' (duration: 2.263088376s)","trace[875879717] 'applied index is now lower than readState.Index' (duration: 3.453137ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T21:28:57.658951Z","caller":"traceutil/trace.go:171","msg":"trace[1721032562] transaction","detail":"{read_only:false; response_revision:6093; number_of_response:1; }","duration":"1.02848994s","start":"2026-05-15T21:28:56.630449Z","end":"2026-05-15T21:28:57.658939Z","steps":["trace[1721032562] 'process raft request' (duration: 1.028230476s)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:28:57.658983Z","caller":"traceutil/trace.go:171","msg":"trace[415198630] transaction","detail":"{read_only:false; response_revision:6094; number_of_response:1; }","duration":"1.026572406s","start":"2026-05-15T21:28:56.632401Z","end":"2026-05-15T21:28:57.658973Z","steps":["trace[415198630] 'process raft request' (duration: 1.026491892s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:57.658994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:56.630439Z","time spent":"1.028532383s","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T21:28:57.659023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:56.632393Z","time spent":"1.026608849s","remote":"127.0.0.1:49584","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-05-15T21:28:57.65905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030054185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T21:28:57.659073Z","caller":"traceutil/trace.go:171","msg":"trace[750855813] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:6094; }","duration":"1.030089477s","start":"2026-05-15T21:28:56.628977Z","end":"2026-05-15T21:28:57.659067Z","steps":["trace[750855813] 'agreement among raft nodes before linearized reading' (duration: 1.030028164s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:57.659092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:56.628923Z","time spent":"1.030163181s","remote":"127.0.0.1:49598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":3,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-15T21:28:57.65932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028463959s","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-05-15T21:28:57.659336Z","caller":"traceutil/trace.go:171","msg":"trace[1116896778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6094; }","duration":"1.028528522s","start":"2026-05-15T21:28:56.630803Z","end":"2026-05-15T21:28:57.659331Z","steps":["trace[1116896778] 'agreement among raft nodes before linearized reading' (duration: 1.028453678s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:57.659353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:56.630784Z","time spent":"1.028565194s","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T21:28:57.659355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02903753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T21:28:57.659385Z","caller":"traceutil/trace.go:171","msg":"trace[1148430827] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:6094; }","duration":"1.029082852s","start":"2026-05-15T21:28:56.630294Z","end":"2026-05-15T21:28:57.659377Z","steps":["trace[1148430827] 'agreement among raft nodes before linearized reading' (duration: 1.0290415s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:57.659407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:56.630289Z","time spent":"1.029112474s","remote":"127.0.0.1:44296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-15T21:28:57.659425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028902442s","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-05-15T21:28:57.659442Z","caller":"traceutil/trace.go:171","msg":"trace[1881323845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6094; }","duration":"1.028927174s","start":"2026-05-15T21:28:56.63051Z","end":"2026-05-15T21:28:57.659437Z","steps":["trace[1881323845] 'agreement among raft nodes before linearized reading' (duration: 1.028882542s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T21:28:57.659455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T21:28:56.630507Z","time spent":"1.028944965s","remote":"127.0.0.1:49584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-15T21:31:20.458529Z","caller":"traceutil/trace.go:171","msg":"trace[1381766146] transaction","detail":"{read_only:false; response_revision:7086; number_of_response:1; }","duration":"210.076622ms","start":"2026-05-15T21:31:20.248431Z","end":"2026-05-15T21:31:20.458508Z","steps":["trace[1381766146] 'process raft request' (duration: 209.958985ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:31:20.462185Z","caller":"traceutil/trace.go:171","msg":"trace[2103292908] transaction","detail":"{read_only:false; response_revision:7087; number_of_response:1; }","duration":"181.032014ms","start":"2026-05-15T21:31:20.281133Z","end":"2026-05-15T21:31:20.462165Z","steps":["trace[2103292908] 'process raft request' (duration: 180.836183ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T21:32:29.681941Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5600} {"level":"info","ts":"2026-05-15T21:32:29.844176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5600,"took":"160.996143ms","hash":2528153899,"current-db-size-bytes":53067776,"current-db-size":"53 MB","current-db-size-in-use-bytes":18178048,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-15T21:32:29.844304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2528153899,"revision":5600,"compact-revision":2925}